builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0243 starttime: 1485970725.44 results: success (0) buildid: 20170201081808 builduid: ff39e7f7de9f40ab82f9fb0fc4fd3131 revision: f3d187bd0733b1182dffc97b5dfe623e18f92a44 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-01 09:38:45.439768) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-01 09:38:45.440187) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-01 09:38:45.440459) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-01 09:38:45.468313) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 09:38:45.468697) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FJptP1e35K/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-01 09:38:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2017-02-01 09:38:45 (18.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129442 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 09:38:45.622292) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 09:38:45.622597) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FJptP1e35K/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.025002 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 09:38:45.673710) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 09:38:45.674070) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f3d187bd0733b1182dffc97b5dfe623e18f92a44 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f3d187bd0733b1182dffc97b5dfe623e18f92a44 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FJptP1e35K/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-01 09:38:45,780 truncating revision to first 12 chars 2017-02-01 09:38:45,780 Setting DEBUG logging. 2017-02-01 09:38:45,780 attempt 1/10 2017-02-01 09:38:45,780 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f3d187bd0733?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-01 09:38:46,433 unpacking tar archive at: mozilla-central-f3d187bd0733/testing/mozharness/ program finished with exit code 0 elapsedTime=0.928774 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 09:38:46.624018) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-01 09:38:46.624334) ========= script_repo_revision: f3d187bd0733b1182dffc97b5dfe623e18f92a44 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-01 09:38:46.624705) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-01 09:38:46.624981) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-01 09:38:46.644816) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-01 09:38:46.645220) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FJptP1e35K/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:38:46 INFO - MultiFileLogger online at 20170201 09:38:46 in /builds/slave/test 09:38:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:38:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:38:46 INFO - {'append_to_log': False, 09:38:46 INFO - 'base_work_dir': '/builds/slave/test', 09:38:46 INFO - 'blob_upload_branch': 'Firefox', 09:38:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:38:46 INFO - 'branch': 'Firefox', 09:38:46 INFO - 'buildbot_json_path': 'buildprops.json', 09:38:46 INFO - 'config_files': ('talos/mac_config.py',), 09:38:46 INFO - 'default_actions': ('clobber', 09:38:46 INFO - 'read-buildbot-config', 09:38:46 INFO - 'download-and-extract', 09:38:46 INFO - 'populate-webroot', 09:38:46 INFO - 'create-virtualenv', 09:38:46 INFO - 'install', 09:38:46 INFO - 'run-tests'), 09:38:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:38:46 INFO - 'download_minidump_stackwalk': True, 09:38:46 INFO - 'download_symbols': 'ondemand', 09:38:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:38:46 INFO - 'tooltool.py': '/tools/tooltool.py', 09:38:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:38:46 INFO - '/tools/misc-python/virtualenv.py')}, 09:38:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:38:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:38:46 INFO - 'installer_path': 'installer.exe', 09:38:46 INFO - 'log_level': 'info', 09:38:46 INFO - 'log_name': 'talos', 09:38:46 INFO - 'log_to_console': True, 09:38:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:38:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:38:46 INFO - 'opt_config_files': (), 09:38:46 INFO - 'pip_index': False, 09:38:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:38:46 INFO - 'cmd': ('bash', 09:38:46 INFO - '-c', 09:38:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:38:46 INFO - 'enabled': True, 09:38:46 INFO - 'halt_on_failure': False, 09:38:46 INFO - 'name': 'check_screen_resolution'},), 09:38:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:38:46 INFO - 'cmd': ('bash', 09:38:46 INFO - '-c', 09:38:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:38:46 INFO - 'enabled': True, 09:38:46 INFO - 'halt_on_failure': False, 09:38:46 INFO - 'name': 'check_screen_resolution'},), 09:38:46 INFO - 'run_cmd_checks_enabled': True, 09:38:46 INFO - 'sps_profile': False, 09:38:46 INFO - 'sps_profile_interval': 0, 09:38:46 INFO - 'suite': 'dromaeojs', 09:38:46 INFO - 'system_bits': '32', 09:38:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:38:46 INFO - 'title': 't-yosemite-r7-0243', 09:38:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:38:46 INFO - 'use_talos_json': True, 09:38:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:38:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:38:46 INFO - 'work_dir': 'build'} 09:38:46 INFO - [mozharness: 2017-02-01 17:38:46.860956Z] Running clobber step. 09:38:46 INFO - Running pre-action listener: _resource_record_pre_action 09:38:46 INFO - Running main action method: clobber 09:38:46 INFO - rmtree: /builds/slave/test/build 09:38:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:38:49 INFO - Running post-action listener: _resource_record_post_action 09:38:49 INFO - [mozharness: 2017-02-01 17:38:49.173393Z] Finished clobber step (success) 09:38:49 INFO - [mozharness: 2017-02-01 17:38:49.173517Z] Running read-buildbot-config step. 09:38:49 INFO - Running pre-action listener: _resource_record_pre_action 09:38:49 INFO - Running main action method: read_buildbot_config 09:38:49 INFO - Using buildbot properties: 09:38:49 INFO - { 09:38:49 INFO - "project": "", 09:38:49 INFO - "product": "firefox", 09:38:49 INFO - "script_repo_revision": "production", 09:38:49 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:38:49 INFO - "repository": "", 09:38:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:38:49 INFO - "buildid": "20170201081808", 09:38:49 INFO - "pgo_build": "False", 09:38:49 INFO - "basedir": "/builds/slave/test", 09:38:49 INFO - "buildnumber": 51, 09:38:49 INFO - "slavename": "t-yosemite-r7-0243", 09:38:49 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:38:49 INFO - "platform": "yosemite_r7", 09:38:49 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:38:49 INFO - "branch": "mozilla-central", 09:38:49 INFO - "repo_path": "mozilla-central", 09:38:49 INFO - "revision": "f3d187bd0733b1182dffc97b5dfe623e18f92a44", 09:38:49 INFO - "stage_platform": "macosx64", 09:38:49 INFO - "builduid": "ff39e7f7de9f40ab82f9fb0fc4fd3131", 09:38:49 INFO - "slavebuilddir": "test" 09:38:49 INFO - } 09:38:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 09:38:49 INFO - Running post-action listener: _resource_record_post_action 09:38:49 INFO - [mozharness: 2017-02-01 17:38:49.176837Z] Finished read-buildbot-config step (success) 09:38:49 INFO - [mozharness: 2017-02-01 17:38:49.176968Z] Running download-and-extract step. 09:38:49 INFO - Running pre-action listener: _resource_record_pre_action 09:38:49 INFO - Running main action method: download_and_extract 09:38:49 INFO - mkdir: /builds/slave/test/build/tests 09:38:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:38:49 INFO - trying https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 09:38:49 INFO - Downloading https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 09:38:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 09:38:49 INFO - Downloaded 1199 bytes. 09:38:49 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 09:38:49 INFO - Using the following test package requirements: 09:38:49 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 09:38:49 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:38:49 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 09:38:49 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:38:49 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 09:38:49 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:38:49 INFO - u'jsshell-mac.zip'], 09:38:49 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:38:49 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 09:38:49 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 09:38:49 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:38:49 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 09:38:49 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:38:49 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 09:38:49 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:38:49 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 09:38:49 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:38:49 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 09:38:49 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:38:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 09:38:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:38:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 09:38:51 INFO - Expected file size: 16375178 09:38:51 INFO - Obtained file size: 16375178 09:38:58 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:38:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 09:38:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:38:58 INFO - Fetch https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 09:38:59 INFO - Expected file size: 16375178 09:38:59 INFO - Obtained file size: 16375178 09:39:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 09:39:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:39:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 09:39:07 INFO - Expected file size: 14403454 09:39:07 INFO - Obtained file size: 14403454 09:39:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:08 INFO - trying https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:39:08 INFO - Downloading https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 09:39:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 09:39:11 INFO - Downloaded 70155376 bytes. 09:39:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:39:11 INFO - mkdir: /builds/slave/test/properties 09:39:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:39:11 INFO - Writing to file /builds/slave/test/properties/build_url 09:39:11 INFO - Contents: 09:39:11 INFO - build_url:https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:39:12 INFO - Running post-action listener: _resource_record_post_action 09:39:12 INFO - Running post-action listener: set_extra_try_arguments 09:39:12 INFO - [mozharness: 2017-02-01 17:39:12.211098Z] Finished download-and-extract step (success) 09:39:12 INFO - [mozharness: 2017-02-01 17:39:12.211232Z] Running populate-webroot step. 09:39:12 INFO - Running pre-action listener: _resource_record_pre_action 09:39:12 INFO - Running main action method: populate_webroot 09:39:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:39:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:39:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:39:12 INFO - '--tppagecycles', 09:39:12 INFO - '7'], 09:39:12 INFO - 'tests': ['tsvgm']}}, 09:39:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:39:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:39:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:39:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:39:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:39:12 INFO - 'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:39:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:39:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:39:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:39:12 INFO - 'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['damp', 'tps']}, 09:39:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:39:12 INFO - 'tests': ['damp', 'tps']}, 09:39:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['dromaeo_dom']}, 09:39:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:39:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['basic_compositor_video']}, 09:39:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:39:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['a11yr', 09:39:12 INFO - 'ts_paint', 09:39:12 INFO - 'tpaint', 09:39:12 INFO - 'sessionrestore', 09:39:12 INFO - 'sessionrestore_no_auto_restore', 09:39:12 INFO - 'tabpaint']}, 09:39:12 INFO - 'other-e10s': {'tests': ['a11yr', 09:39:12 INFO - 'ts_paint', 09:39:12 INFO - 'tpaint', 09:39:12 INFO - 'sessionrestore', 09:39:12 INFO - 'sessionrestore_no_auto_restore', 09:39:12 INFO - 'tabpaint']}, 09:39:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:39:12 INFO - 'ts_paint', 09:39:12 INFO - 'tpaint', 09:39:12 INFO - 'sessionrestore', 09:39:12 INFO - 'sessionrestore_no_auto_restore', 09:39:12 INFO - 'tabpaint']}, 09:39:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:39:12 INFO - 'ts_paint', 09:39:12 INFO - 'tpaint', 09:39:12 INFO - 'sessionrestore', 09:39:12 INFO - 'sessionrestore_no_auto_restore', 09:39:12 INFO - 'tabpaint']}, 09:39:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['a11yr', 09:39:12 INFO - 'ts_paint', 09:39:12 INFO - 'tpaint', 09:39:12 INFO - 'sessionrestore', 09:39:12 INFO - 'sessionrestore_no_auto_restore', 09:39:12 INFO - 'tabpaint']}, 09:39:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['a11yr', 09:39:12 INFO - 'ts_paint', 09:39:12 INFO - 'tpaint', 09:39:12 INFO - 'sessionrestore', 09:39:12 INFO - 'sessionrestore_no_auto_restore', 09:39:12 INFO - 'tabpaint']}, 09:39:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['tsvgx', 09:39:12 INFO - 'tsvgr_opacity', 09:39:12 INFO - 'tart', 09:39:12 INFO - 'tscrollx', 09:39:12 INFO - 'cart', 09:39:12 INFO - 'tsvg_static']}, 09:39:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:39:12 INFO - 'tsvgr_opacity', 09:39:12 INFO - 'tart', 09:39:12 INFO - 'tscrollx', 09:39:12 INFO - 'cart', 09:39:12 INFO - 'tsvg_static']}, 09:39:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:39:12 INFO - 'talos_options': ['--disable-e10s'], 09:39:12 INFO - 'tests': ['tp5o']}, 09:39:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:39:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:39:12 INFO - 'talos_options': ['--disable-e10s', 09:39:12 INFO - '--xperf_path', 09:39:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:39:12 INFO - 'tests': ['tp5n']}, 09:39:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:39:12 INFO - 'talos_options': ['--xperf_path', 09:39:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:39:12 INFO - 'tests': ['tp5n']}}, 09:39:12 INFO - 'talos.zip': {'path': '', 09:39:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:39:12 INFO - Running post-action listener: _resource_record_post_action 09:39:12 INFO - [mozharness: 2017-02-01 17:39:12.229876Z] Finished populate-webroot step (success) 09:39:12 INFO - [mozharness: 2017-02-01 17:39:12.229988Z] Running create-virtualenv step. 09:39:12 INFO - Running pre-action listener: _resource_record_pre_action 09:39:12 INFO - Running main action method: create_virtualenv 09:39:12 INFO - Creating virtualenv /builds/slave/test/build/venv 09:39:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:39:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:39:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:39:12 INFO - Using real prefix '/tools/python27' 09:39:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:39:13 INFO - Installing distribute.............................................................................................................................................................................................done. 09:39:15 INFO - Installing pip.................done. 09:39:15 INFO - Return code: 0 09:39:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:39:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:39:16 INFO - Reading from file tmpfile_stdout 09:39:16 INFO - Output received: 09:39:16 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:39:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1062bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce71cd3280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10608ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:39:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:39:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 09:39:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:16 INFO - 'HOME': '/Users/cltbld', 09:39:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:16 INFO - 'LOGNAME': 'cltbld', 09:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:16 INFO - 'MOZ_NO_REMOTE': '1', 09:39:16 INFO - 'NO_EM_RESTART': '1', 09:39:16 INFO - 'PAGER': '/bin/cat', 09:39:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:16 INFO - 'PWD': '/builds/slave/test', 09:39:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:16 INFO - 'SHELL': '/bin/bash', 09:39:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 09:39:16 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:39:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:16 INFO - 'USER': 'cltbld', 09:39:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:16 INFO - 'XPC_FLAGS': '0x0', 09:39:16 INFO - 'XPC_SERVICE_NAME': '0', 09:39:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:39:16 INFO - Cleaning up... 09:39:16 INFO - Return code: 0 09:39:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1062bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce71cd3280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10608ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:39:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:39:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 09:39:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:16 INFO - 'HOME': '/Users/cltbld', 09:39:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:16 INFO - 'LOGNAME': 'cltbld', 09:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:16 INFO - 'MOZ_NO_REMOTE': '1', 09:39:16 INFO - 'NO_EM_RESTART': '1', 09:39:16 INFO - 'PAGER': '/bin/cat', 09:39:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:16 INFO - 'PWD': '/builds/slave/test', 09:39:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:16 INFO - 'SHELL': '/bin/bash', 09:39:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 09:39:16 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:39:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:16 INFO - 'USER': 'cltbld', 09:39:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:16 INFO - 'XPC_FLAGS': '0x0', 09:39:16 INFO - 'XPC_SERVICE_NAME': '0', 09:39:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:16 INFO - Downloading/unpacking psutil>=3.1.1 09:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:39:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:39:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:39:20 INFO - Installing collected packages: psutil 09:39:20 INFO - Running setup.py install for psutil 09:39:20 INFO - building 'psutil._psutil_osx' extension 09:39:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:39:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:39:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:39:21 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:39:21 INFO - building 'psutil._psutil_posix' extension 09:39:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:39:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:39:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:39:21 INFO - ^ 09:39:21 INFO - 1 warning generated. 09:39:21 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:39:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:39:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:39:21 INFO - Successfully installed psutil 09:39:21 INFO - Cleaning up... 09:39:21 INFO - Return code: 0 09:39:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:39:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1062bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce71cd3280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10608ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:39:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:39:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:39:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 09:39:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:21 INFO - 'HOME': '/Users/cltbld', 09:39:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:21 INFO - 'LOGNAME': 'cltbld', 09:39:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:21 INFO - 'MOZ_NO_REMOTE': '1', 09:39:21 INFO - 'NO_EM_RESTART': '1', 09:39:21 INFO - 'PAGER': '/bin/cat', 09:39:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:21 INFO - 'PWD': '/builds/slave/test', 09:39:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:21 INFO - 'SHELL': '/bin/bash', 09:39:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 09:39:21 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:39:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:21 INFO - 'USER': 'cltbld', 09:39:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:21 INFO - 'XPC_FLAGS': '0x0', 09:39:21 INFO - 'XPC_SERVICE_NAME': '0', 09:39:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:39:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:39:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:39:24 INFO - Installing collected packages: mozsystemmonitor 09:39:24 INFO - Running setup.py install for mozsystemmonitor 09:39:24 INFO - Successfully installed mozsystemmonitor 09:39:24 INFO - Cleaning up... 09:39:24 INFO - Return code: 0 09:39:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1062bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce71cd3280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10608ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:39:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:39:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:39:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 09:39:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:24 INFO - 'HOME': '/Users/cltbld', 09:39:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:24 INFO - 'LOGNAME': 'cltbld', 09:39:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:24 INFO - 'MOZ_NO_REMOTE': '1', 09:39:24 INFO - 'NO_EM_RESTART': '1', 09:39:24 INFO - 'PAGER': '/bin/cat', 09:39:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:24 INFO - 'PWD': '/builds/slave/test', 09:39:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:24 INFO - 'SHELL': '/bin/bash', 09:39:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 09:39:24 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:39:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:24 INFO - 'USER': 'cltbld', 09:39:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:24 INFO - 'XPC_FLAGS': '0x0', 09:39:24 INFO - 'XPC_SERVICE_NAME': '0', 09:39:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:24 INFO - Downloading/unpacking jsonschema==2.5.1 09:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:39:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:27 INFO - Downloading functools32-3.2.3-2.tar.gz 09:39:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:39:27 INFO - warning: no files found matching '*.txt' 09:39:27 INFO - no previously-included directories found matching 'build' 09:39:27 INFO - no previously-included directories found matching 'dist' 09:39:27 INFO - no previously-included directories found matching '.git*' 09:39:27 INFO - Installing collected packages: jsonschema, functools32 09:39:27 INFO - Running setup.py install for functools32 09:39:28 INFO - warning: no files found matching '*.txt' 09:39:28 INFO - no previously-included directories found matching 'build' 09:39:28 INFO - no previously-included directories found matching 'dist' 09:39:28 INFO - no previously-included directories found matching '.git*' 09:39:28 INFO - Successfully installed jsonschema functools32 09:39:28 INFO - Cleaning up... 09:39:28 INFO - Return code: 0 09:39:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1062bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce71cd3280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10608ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:39:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:39:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:39:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 09:39:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:28 INFO - 'HOME': '/Users/cltbld', 09:39:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:28 INFO - 'LOGNAME': 'cltbld', 09:39:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:28 INFO - 'MOZ_NO_REMOTE': '1', 09:39:28 INFO - 'NO_EM_RESTART': '1', 09:39:28 INFO - 'PAGER': '/bin/cat', 09:39:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:28 INFO - 'PWD': '/builds/slave/test', 09:39:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:28 INFO - 'SHELL': '/bin/bash', 09:39:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 09:39:28 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:39:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:28 INFO - 'USER': 'cltbld', 09:39:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:28 INFO - 'XPC_FLAGS': '0x0', 09:39:28 INFO - 'XPC_SERVICE_NAME': '0', 09:39:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:39:28 INFO - Cleaning up... 09:39:28 INFO - Return code: 0 09:39:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1062bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce71cd3280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10608ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:39:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:39:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:39:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 09:39:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:28 INFO - 'HOME': '/Users/cltbld', 09:39:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:28 INFO - 'LOGNAME': 'cltbld', 09:39:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:28 INFO - 'MOZ_NO_REMOTE': '1', 09:39:28 INFO - 'NO_EM_RESTART': '1', 09:39:28 INFO - 'PAGER': '/bin/cat', 09:39:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:28 INFO - 'PWD': '/builds/slave/test', 09:39:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:28 INFO - 'SHELL': '/bin/bash', 09:39:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 09:39:28 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:39:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:28 INFO - 'USER': 'cltbld', 09:39:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:28 INFO - 'XPC_FLAGS': '0x0', 09:39:28 INFO - 'XPC_SERVICE_NAME': '0', 09:39:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:28 INFO - Downloading/unpacking blobuploader==1.2.4 09:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:31 INFO - Downloading blobuploader-1.2.4.tar.gz 09:39:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:39:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:39:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:39:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:32 INFO - Downloading docopt-0.6.1.tar.gz 09:39:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:39:32 INFO - Installing collected packages: blobuploader, requests, docopt 09:39:32 INFO - Running setup.py install for blobuploader 09:39:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:39:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:39:32 INFO - Running setup.py install for requests 09:39:33 INFO - Running setup.py install for docopt 09:39:33 INFO - Successfully installed blobuploader requests docopt 09:39:33 INFO - Cleaning up... 09:39:33 INFO - Return code: 0 09:39:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:39:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1062bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce71cd3280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10608ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:39:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:39:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:39:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 09:39:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:33 INFO - 'HOME': '/Users/cltbld', 09:39:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:33 INFO - 'LOGNAME': 'cltbld', 09:39:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:33 INFO - 'MOZ_NO_REMOTE': '1', 09:39:33 INFO - 'NO_EM_RESTART': '1', 09:39:33 INFO - 'PAGER': '/bin/cat', 09:39:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:33 INFO - 'PWD': '/builds/slave/test', 09:39:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:33 INFO - 'SHELL': '/bin/bash', 09:39:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 09:39:33 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:39:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:33 INFO - 'USER': 'cltbld', 09:39:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:33 INFO - 'XPC_FLAGS': '0x0', 09:39:33 INFO - 'XPC_SERVICE_NAME': '0', 09:39:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:39:33 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-xSw338-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:39:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:39:33 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-arNIa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:39:34 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-40VK0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:39:34 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-XfQtQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:39:34 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-mrXXWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:39:34 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-HGQ8Sv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:39:34 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-YUi6SF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:39:34 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-i5trAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:39:34 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-m2eYso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:39:34 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-478yfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:39:34 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-HBm6ug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:39:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:39:34 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-AlTGkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:39:35 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-hoBna8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:39:35 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-8Okodr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:35 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-oHj1O_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:39:35 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-ptn4B2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:39:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:39:35 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-_iWEJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:39:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:39:35 INFO - Running setup.py install for manifestparser 09:39:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:39:35 INFO - Running setup.py install for mozcrash 09:39:35 INFO - Running setup.py install for mozdebug 09:39:36 INFO - Running setup.py install for mozdevice 09:39:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:39:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:39:36 INFO - Running setup.py install for mozfile 09:39:36 INFO - Running setup.py install for mozhttpd 09:39:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:39:36 INFO - Running setup.py install for mozinfo 09:39:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:39:36 INFO - Running setup.py install for mozInstall 09:39:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:39:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:39:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:39:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:39:36 INFO - Running setup.py install for mozleak 09:39:36 INFO - Running setup.py install for mozlog 09:39:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:39:37 INFO - Running setup.py install for moznetwork 09:39:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:39:37 INFO - Running setup.py install for mozprocess 09:39:37 INFO - Running setup.py install for mozprofile 09:39:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:39:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:39:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:39:37 INFO - Running setup.py install for mozrunner 09:39:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:39:37 INFO - Running setup.py install for mozscreenshot 09:39:37 INFO - Running setup.py install for moztest 09:39:38 INFO - Running setup.py install for mozversion 09:39:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:39:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:39:38 INFO - Cleaning up... 09:39:38 INFO - Return code: 0 09:39:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1062bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce71cd3280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10608ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:39:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:39:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 09:39:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:38 INFO - 'HOME': '/Users/cltbld', 09:39:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:38 INFO - 'LOGNAME': 'cltbld', 09:39:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:38 INFO - 'MOZ_NO_REMOTE': '1', 09:39:38 INFO - 'NO_EM_RESTART': '1', 09:39:38 INFO - 'PAGER': '/bin/cat', 09:39:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:38 INFO - 'PWD': '/builds/slave/test', 09:39:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:38 INFO - 'SHELL': '/bin/bash', 09:39:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 09:39:38 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:39:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:38 INFO - 'USER': 'cltbld', 09:39:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:38 INFO - 'XPC_FLAGS': '0x0', 09:39:38 INFO - 'XPC_SERVICE_NAME': '0', 09:39:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:39:38 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-2D3l88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:39:38 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:39:38 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-lEHggX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:39:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:39:38 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-Rfp3NF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:39:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:39:38 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-i5jHG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:39:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:39:38 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-tgb2Cp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:39:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:39:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:39:38 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-wPtDE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:39:39 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-ZcM0lK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:39:39 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-sobDEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:39:39 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-pO4Otm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:39:39 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-GGopRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:39:39 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-QRh5p0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:39:39 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-emHMU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:39:39 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-KFEawF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:39:39 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-vc6Suw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:39:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:39:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:39 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-T4DzKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:39:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:39:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:39:40 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-PKmqgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:39:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:39:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:39:40 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-yS9GZc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:39:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:39:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:39:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:39:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:39:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:39:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:39:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:39:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:39:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:39:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:39:43 INFO - Downloading blessings-1.6.tar.gz 09:39:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:39:43 INFO - Installing collected packages: blessings 09:39:43 INFO - Running setup.py install for blessings 09:39:43 INFO - Successfully installed blessings 09:39:43 INFO - Cleaning up... 09:39:43 INFO - Return code: 0 09:39:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:39:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:39:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:39:43 INFO - Reading from file tmpfile_stdout 09:39:43 INFO - Current package versions: 09:39:43 INFO - blessings == 1.6 09:39:43 INFO - blobuploader == 1.2.4 09:39:43 INFO - docopt == 0.6.1 09:39:43 INFO - functools32 == 3.2.3-2 09:39:43 INFO - jsonschema == 2.5.1 09:39:43 INFO - manifestparser == 1.1 09:39:43 INFO - mozInstall == 1.12 09:39:43 INFO - mozcrash == 1.0 09:39:43 INFO - mozdebug == 0.1 09:39:43 INFO - mozdevice == 0.49 09:39:43 INFO - mozfile == 1.2 09:39:43 INFO - mozhttpd == 0.7 09:39:43 INFO - mozinfo == 0.9 09:39:43 INFO - mozleak == 0.1 09:39:43 INFO - mozlog == 3.4 09:39:43 INFO - moznetwork == 0.27 09:39:43 INFO - mozprocess == 0.24 09:39:43 INFO - mozprofile == 0.28 09:39:43 INFO - mozrunner == 6.13 09:39:43 INFO - mozscreenshot == 0.1 09:39:43 INFO - mozsystemmonitor == 0.3 09:39:43 INFO - moztest == 0.8 09:39:43 INFO - mozversion == 1.4 09:39:43 INFO - psutil == 3.1.1 09:39:43 INFO - requests == 1.2.3 09:39:43 INFO - wsgiref == 0.1.2 09:39:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:39:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1062bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce71cd3280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10608ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:39:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:39:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:39:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 09:39:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:44 INFO - 'HOME': '/Users/cltbld', 09:39:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:44 INFO - 'LOGNAME': 'cltbld', 09:39:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:44 INFO - 'MOZ_NO_REMOTE': '1', 09:39:44 INFO - 'NO_EM_RESTART': '1', 09:39:44 INFO - 'PAGER': '/bin/cat', 09:39:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:44 INFO - 'PWD': '/builds/slave/test', 09:39:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:44 INFO - 'SHELL': '/bin/bash', 09:39:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 09:39:44 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:39:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:44 INFO - 'USER': 'cltbld', 09:39:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:44 INFO - 'XPC_FLAGS': '0x0', 09:39:44 INFO - 'XPC_SERVICE_NAME': '0', 09:39:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:39:44 INFO - Cleaning up... 09:39:44 INFO - Return code: 0 09:39:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:39:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:39:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:39:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:39:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:39:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1062bbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1062be768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce71cd3280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10608ebe8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:39:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:39:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:39:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 09:39:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:44 INFO - 'HOME': '/Users/cltbld', 09:39:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:44 INFO - 'LOGNAME': 'cltbld', 09:39:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:44 INFO - 'MOZ_NO_REMOTE': '1', 09:39:44 INFO - 'NO_EM_RESTART': '1', 09:39:44 INFO - 'PAGER': '/bin/cat', 09:39:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:44 INFO - 'PWD': '/builds/slave/test', 09:39:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:44 INFO - 'SHELL': '/bin/bash', 09:39:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 09:39:44 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:39:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:44 INFO - 'USER': 'cltbld', 09:39:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:44 INFO - 'XPC_FLAGS': '0x0', 09:39:44 INFO - 'XPC_SERVICE_NAME': '0', 09:39:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:39:44 INFO - Cleaning up... 09:39:44 INFO - Return code: 0 09:39:44 INFO - Running post-action listener: _resource_record_post_action 09:39:44 INFO - Running post-action listener: _start_resource_monitoring 09:39:44 INFO - Starting resource monitoring. 09:39:44 INFO - [mozharness: 2017-02-01 17:39:44.928887Z] Finished create-virtualenv step (success) 09:39:44 INFO - [mozharness: 2017-02-01 17:39:44.929835Z] Running install step. 09:39:44 INFO - Running pre-action listener: _resource_record_pre_action 09:39:44 INFO - Running main action method: install 09:39:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:39:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:39:45 INFO - Reading from file tmpfile_stdout 09:39:45 INFO - Detecting whether we're running mozinstall >=1.0... 09:39:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:39:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:39:45 INFO - Reading from file tmpfile_stdout 09:39:45 INFO - Output received: 09:39:45 INFO - Usage: mozinstall [options] installer 09:39:45 INFO - Options: 09:39:45 INFO - -h, --help show this help message and exit 09:39:45 INFO - -d DEST, --destination=DEST 09:39:45 INFO - Directory to install application into. [default: 09:39:45 INFO - "/builds/slave/test"] 09:39:45 INFO - --app=APP Application being installed. [default: firefox] 09:39:45 INFO - mkdir: /builds/slave/test/build/application 09:39:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:39:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:40:07 INFO - Reading from file tmpfile_stdout 09:40:07 INFO - Output received: 09:40:07 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:40:07 INFO - Running post-action listener: _resource_record_post_action 09:40:07 INFO - [mozharness: 2017-02-01 17:40:07.211039Z] Finished install step (success) 09:40:07 INFO - [mozharness: 2017-02-01 17:40:07.211166Z] Running run-tests step. 09:40:07 INFO - Running pre-action listener: _resource_record_pre_action 09:40:07 INFO - Running main action method: run_tests 09:40:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:40:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:40:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:40:07 INFO - 2017-02-01 09:40:07.223 screenresolution[1623:7228] starting screenresolution argv=screenresolution get 09:40:07 INFO - 2017-02-01 09:40:07.226 screenresolution[1623:7228] Display 0: 1600x1200x32@60 09:40:07 INFO - 2017-02-01 09:40:07.231 screenresolution[1624:7231] starting screenresolution argv=screenresolution list 09:40:07 INFO - Available Modes on Display 0 09:40:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:40:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:40:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:40:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:40:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:40:07 INFO - Intel Iris: 09:40:07 INFO - Chipset Model: Intel Iris 09:40:07 INFO - Type: GPU 09:40:07 INFO - Bus: Built-In 09:40:07 INFO - VRAM (Dynamic, Max): 1536 MB 09:40:07 INFO - Vendor: Intel (0x8086) 09:40:07 INFO - Device ID: 0x0a2e 09:40:07 INFO - Revision ID: 0x0009 09:40:07 INFO - Displays: 09:40:07 INFO - Display: 09:40:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:40:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:40:07 INFO - Display Serial Number: 1600x1200 60 09:40:07 INFO - Main Display: Yes 09:40:07 INFO - Mirror: Off 09:40:07 INFO - Online: Yes 09:40:07 INFO - Rotation: Supported 09:40:07 INFO - Return code: 0 09:40:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:40:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:40:07 INFO - Python 2.7.3 09:40:07 INFO - Return code: 0 09:40:07 INFO - grabbing minidump binary from tooltool 09:40:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:07 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106099d30>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105fc0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10608ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:40:07 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:40:07 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:40:07 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:40:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:40:07 INFO - Return code: 0 09:40:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:40:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:40:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:40:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:40:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:40:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:40:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0243 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:40:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJptP1e35K/Render', 09:40:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:07 INFO - 'HOME': '/Users/cltbld', 09:40:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:07 INFO - 'LOGNAME': 'cltbld', 09:40:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:40:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:40:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:07 INFO - 'MOZ_NO_REMOTE': '1', 09:40:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:40:07 INFO - 'NO_EM_RESTART': '1', 09:40:07 INFO - 'PAGER': '/bin/cat', 09:40:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:07 INFO - 'PWD': '/builds/slave/test', 09:40:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:40:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:07 INFO - 'SHELL': '/bin/bash', 09:40:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners', 09:40:07 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:40:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:07 INFO - 'USER': 'cltbld', 09:40:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:07 INFO - 'XPC_FLAGS': '0x0', 09:40:07 INFO - 'XPC_SERVICE_NAME': '0', 09:40:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:40:07 INFO - mozversion application_buildid: 20170201081808 09:40:07 INFO - mozversion application_changeset: f3d187bd0733b1182dffc97b5dfe623e18f92a44 09:40:07 INFO - mozversion application_display_name: Nightly 09:40:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:40:07 INFO - mozversion application_name: Firefox 09:40:07 INFO - mozversion application_remotingname: firefox 09:40:07 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:40:07 INFO - mozversion application_vendor: Mozilla 09:40:07 INFO - mozversion application_version: 54.0a1 09:40:07 INFO - mozversion platform_buildid: 20170201081808 09:40:07 INFO - mozversion platform_changeset: f3d187bd0733b1182dffc97b5dfe623e18f92a44 09:40:07 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:40:07 INFO - mozversion platform_version: 54.0a1 09:40:07 INFO - using testdate: 1485970807 09:40:07 INFO - actual date: 1485970807 09:40:07 INFO - starting webserver on 'localhost:49241' 09:40:07 INFO - SUITE-START | Running 2 tests 09:40:07 INFO - TEST-START | dromaeo_css 09:40:07 INFO - operating with platform_type : mac_ 09:40:07 INFO - Initialising browser for dromaeo_css test... 09:40:07 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmptp5kHp/profile http://localhost:49241/getInfo.html) 09:40:10 INFO - PROCESS | 1634 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 09:40:10 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 09:40:10 INFO - PROCESS | 1634 | colorDepth:24 09:40:10 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 09:40:10 INFO - PROCESS | 1634 | __metrics 09:40:10 INFO - PROCESS | 1634 | 1485970810440 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 09:40:10 INFO - PROCESS | 1634 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:40:10 INFO - PROCESS | 1634 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:40:11 INFO - TEST-INFO | 1634: exit 0 09:40:11 INFO - Browser initialized. 09:40:11 INFO - Running cycle 1/1 for dromaeo_css test... 09:40:11 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmptp5kHp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:40:23 INFO - PROCESS | 1636 | RSS: Main: 237981696 09:40:23 INFO - PROCESS | 1636 | 09:43:25 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 09:43:28 INFO - PROCESS | 1636 | RSS: Main: 464388096 09:43:28 INFO - PROCESS | 1636 | 09:45:13 INFO - PROCESS | 1636 | ************************* 09:45:13 INFO - PROCESS | 1636 | A coding exception was thrown and uncaught in a Task. 09:45:13 INFO - PROCESS | 1636 | 09:45:13 ERROR - PROCESS | 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:45:13 INFO - PROCESS | 1636 | Full stack: 09:45:13 INFO - PROCESS | 1636 | ************************* 09:46:32 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 09:46:32 INFO - PROCESS | 1636 | RSS: Main: 372391936 09:46:32 INFO - PROCESS | 1636 | 09:49:34 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 09:49:35 INFO - PROCESS | 1636 | RSS: Main: 357838848 09:49:35 INFO - PROCESS | 1636 | 09:52:37 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 09:52:38 INFO - PROCESS | 1636 | RSS: Main: 335544320 09:52:38 INFO - PROCESS | 1636 | 09:55:41 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 09:55:42 INFO - PROCESS | 1636 | RSS: Main: 334065664 09:55:42 INFO - PROCESS | 1636 | 09:58:46 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 09:58:46 INFO - PROCESS | 1636 | RSS: Main: 454139904 09:58:46 INFO - PROCESS | 1636 | 09:58:46 INFO - PROCESS | 1636 | __start_tp_report 09:58:46 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 09:58:46 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 09:58:46 INFO - PROCESS | 1636 | |i|pagename|runs| 09:58:46 INFO - PROCESS | 1636 | |0;dojo.html;3323.1858194742285;3339.415602269603;3345.933081338375;3351.2962277921633;3393.5418718472997;5511.173324001392;5557.972210138949;5595.132754423071;5638.802641907534;5653.6325138866;5566.972165139179;5623.718814059301;5707.429257074291;5709.457601527859;5735.426457354262;4139.503259608851;5090.05833920724;5123.1546794778815;5168.689878607295;5184.222366644995;5149.330587023682;5246.265522826808;5263.789448422059;5270.4729527047375;5339.5461385782255;5077.0607437623785;5234.371875374979;5246.711430871301;5253.106971814782;5272.446393128724;5088.567471764904;5120.334356533627;5148.6910785353;5163.0190263849745;5171.043356978933;5104.081265372231;5122.462141475129;5146.996335714537;5148.021875843578;5172.689638621695;4609.585137337655;5522.641028333186;5624.550035997151;5633.80281690143;5636.098224284095;5179.585633149339;6046.395360463963;6101.938980610182;6105.847353816146;6146;6158.199434073586;6201.565890387675;6213.1922850029205;6227.813165605039;6290.779822706229;5786.1610066540115;5791.189233507313;5793.768249270024;5801.593888427812;5827.592068555203;6232.043096568182;6236.6258024518675;6271.843203919847;6284;6312.589681670676;5976.103584462366;6019.60872543292;6023.578349515492;6080.75676972925;6134.325224225332;6101.359357267423;6185.597936134137;6231.563790534619;6235.158253635701;6259.561830671809;6061.515078793686;6069.089636554552;6141.385861413823;6159.3224745277985;6159.84600384985;6202.34875338083;6219.40915613016;6221.875562488724;6222.12890195373;6231.003039513656;4759.595434388116;4873.512648735098;4877.6585639004925;4884.0476107158765;4908.092002979461;4323.546027667113;4333.674974376934;4339.067100573392;4361.978190109029;4366.279563871989;5798.420157984167;5808.680522571299;5823.359430462646;5835.44563266489;5845.766169353264;5175.508326708957;6150.846228844225;6277.121203031579;6280.371962803774;6284.654344011124;5608.242887210256;5647.6893770843;5683.403242659544;5749.68376739275;5777.133429985536;5308.0710875596615;5353.892922141613;5355.86610334737;5363.356397232357;5385.299911011543;6307.96846015767;6325.588836725598;6332.936670633235;6336.7465301389275;6346.333634968262;6116.5106791457465;6213.471854892204;6231.594946328474;6249.78125765596;6262.15460912771;6252.124702541739;6268.091126786681;6269.843253918506;6302.086197501429;6318.684065796784;6168.506519478521;6169.259688837368;6177.62934223948;6196.597221180609;6217.409346112112;4816.686915350491;5113.923291150561;6180.3201647817805;6183.288921773874;6250.562460627712;4835.734034628129;5853.502452291603;5901.262342207224;5978.163057172086;6016.09758536223;6004.849878753066;6018.037114061729;6027.60820546663;6057.061155520902;6086.7260973255425;5692.288463942007;5790.160426738184;5794.159846822103;5842.795502157404;5864.560157988253;5926.140709596998;5995.130706047511;6021.608595441282;6058.818235452944;6065.090236464389;5832.475077243069;5835.824925252249;5842.853928651818;5849.327327357409;5855.970720146543 09:58:46 INFO - PROCESS | 1636 | |1;ext.html;462.66919152805735;468.25313624768484;481.09313943217035;503.514108884926;520.5393226994111;36053.45919811202;39033;41063.79468102663;43319.35020974688;43373.56626433735;41608.79195604025;42355.36466952998;42422.36366454505;42599.78700106495;42726.786366068125;8256.174382561756;8987.10128987101;9320.953395233048;9322.440653560774;9338.439693618371;8314.542700151489;11005.61480348184;11245.269057511276;11278.379689117095;11343.092552595772;11052.171087168454;11102.666919992413;11112.222144449892;11125.666230013072;11159.218854680175;8308.875366869503;8514.616842242114;10890.401027943499;10974.122070234362;11010.39442830644;8334.958325208396;8396.286315663176;8407.949006374203;10856.891431085667;11103.278286911325;40825.57110501148;41102.972425689295;41128;41147.38278925818;41327.380089298684;4014.9397759033636;4021.8190181441614;4022.3363145081025;4087.2847251731127;4192.371144328345;30684;30754;30815.075547733602;30854.84572577145;30930.226744331572;2896.6089577906923;3089.1350421881907;3089.675584063686;3149.0395429394025;3159.352332771776;477736;477780;479702;479883;481856.5907170477;57974.7101264491;58314.7084264576;58430.12354814681;58500.707496462244;58528;60701;60777;60781.39218607757;60784.656843874014;60829.69585152046;15480.5355839325;15757.527274181793;15767.842321576867;15784.763228551581;15807.841921580637;11038.558457661604;11299;11299.983001529738;12284.508619655293;12369.567065152676;14946.252687365588;15195.860310476537;15233.466828660943;15536.300866461037;15608.985415947927;4890.963561624787;5493.176023596413;5555.527780138734;6193.88510068192;6213.875722485525;857.6012154348258;861.7716305179007;864.5115509736978;873.7422460374205;881.0704706534667;29676.554851677243;29706.108816735534;30087.548686769715;30306.393872122433;30519.964205905777;946.2524605561667;1042.629866397418;1082.4587706146926;1099.8130317845985;1160.4487868262677;15389.230538473255;15422;15555.30001149951;15730.370785168465;15824.446144385121;610017;611400;611671;613612;615089;58591;58838.705806470694;58902.705486472296;58935;58941.70529147327;1389.9374528146257;1417.9503717369842;1447.7755947828105;1450.7026059657956;1459.5621313606089;24878.55569532815;29250.268743281587;29273.268168295963;29734.85132574323;30249.395012099194;49246.50753492419;49569.75215124046;49603.75198123986;49826.50173498219;49859.002819942674;523893;526564;527039;530757;530863;37416.771661778526;38066.857994260215;38353.61646383612;38480.615193847705;38689.6131038686;38001.61998380091;41469.755907322826;45125.54874451213;45688.54311456843;46545;31755.682443176196;37084.44373334348;37268;38293.80853095828;40072.79963600163;30324.69675303219;37865.43201851919;37993.810030949666;38293.617063829006;39809.60666376663;37624.811875940446;37632.623673762915;37687;38254.23491530209;38476.42285365778;29961.700382995892;31389.372212556078;34073;34384.82807585946;34729.65270347264;20034.799652003294;24925.133609635122;24960.750392495844;25221.991120355713;25739.871300643375 09:58:46 INFO - PROCESS | 1636 | |2;jquery.html;394.91706741584267;395.6775228189024;397.39199025491007;405.43846772220473;424.0670524845339;19402.22391104354;19726.309579164714;20880.37358879236;20918.89540552297;20975.951202439894;20960.37118886432;20978.475538111554;21032.789672103274;21320.89339553304;21326.253581124663;5631.549476041917;5992.131140984555;6054.6367217966845;6057.363976782441;6063.514918806485;5784.652920824743;5872;5934.43622855828;6048.062550304688;6048.576599638005;5804.013317735974;5887.793927212549;5904.7047647617665;5906.468417842393;5907.0548712206055;5655.802046928358;5873.207117039209;5883.264591926009;5914.053751399755;5997;5232.084385232607;5241.816536421207;5244.423113457517;5244.423113457517;5245.6852588844795;24976.62628555429;25406.98372065115;25792.74207257941;25998.87000564985;26241.606375904375;2681.3028612560875;2699.6760388753473;2709.891604335824;2716.9728302717117;2721.319670082479;15282.159481228528;15439.30523126462;15547.455839045691;15642;15858.52424427269;1781.5100847266976;1865.5615930256379;1870.7661542307212;1877.6296547696782;1883.783364913025;120157.19764203543;125130;125332;125569.37215313865;126205.36897315546;11349.375784331776;11390.886091138982;11498.48256828428;11587.536498540132;11764.647060588197;12797;13356.198628082346;14490.347934342979;14518.419263229353;14524.419023238952;8004.319632831274;8357.206065423776;8562.700305489278;8683.478991260545;8704.04255531891;7478.504299140193;8378.581070946428;8650.264727498106;8669.913300867036;8795.164459376349;6182.474489668339;7199;7202.171750248684;7267.818304542323;7304.123505179413;1628.7964004499438;1892.8674992750375;1935.380678182968;1935.9806401936082;1943.5821298420908;1059.6185373265619;1066.5200659703164;1068.3536460441321;1081.7998670245872;1118.139032944628;22479.88760056189;22652.29470827965;22659.97540762369;22901.88549057244;23214.65178022331;1072.694282129605;1079.681493959284;1100.708312297242;1104.1553211792984;1122.405125283601;11104.333739975627;11244;11486.770264594661;11750.735917776849;11767.588134415426;726618.2014539572;729882;743476;753082;755469;141941;141944.29027855;145905;146274;149082.25458872635;888.644542183119;1007.5214273220162;1027.3476342522404;1031.2059714020163;1031.2162756305113;14041.859581404055;17578.91210543939;19718.40844774659;19733.02669733015;19776.604467910274;53530.46469535411;54906.058454739265;56317;57848.13227801502;58063.70968145132;67471;81006;81933.18066819255;82488;82563.33085025985;6319.431251187231;6925.619090949845;8024.558649274112;8174.7956301092945;8361.456505327134;8157.836843262983;13261.66845828862;13324.800127998282;13421.66445838862;13936.860631393962;10564.630237941636;10729.785404292026;10860.891391085988;11189.72025699364;11479.598214062467;9319.767005824908;11357.886421136014;11372.772544549229;11572.537098515964;11721.296722196696;9639.518024098626;11602.709932251424;11970.640880773592;12028.917397434272;12161.513539458323;6309.684515774285;7478.70085196586;7554.357879580078;7733.8839917400155;7812.257835505618;11111.055560277144;11474.311541307214;11503.137264705349;11853.703657408634;11889.28664280146 09:58:46 INFO - PROCESS | 1636 | |3;mootools.html;1357.5859362894316;1424.2095636921517;1424.871761541461;1425.8217722784646;1431.6850292935553;7713.460057795956;8022.91977080229;8041.195880411956;8046.195380461951;8064.435489515737;7340.082489688789;7613.771586852389;7625.466217364773;7959.880601790977;8137.674493020272;1934.941951741443;2256.6050941085286;2299.735530414007;2301.9079236830507;2307.3308740465245;1914.9617007659838;2252.549490101978;2297.2993237062688;2306.7116610423695;2310.897083653875;2013.0941076515626;2094.099537199004;2203.2508946958033;2261.163369553268;2284.5316710074394;2282.0301371916953;2288.7482376938524;2290.8510946788406;2295.21962532738;2303.5047464794984;2225.0765932138142;2282.6005449046515;2300.9654855177187;2307.8038366738847;2314.2941402872284;8293.377996650215;8363.916360836436;8379.078301386842;8382.790430239233;8384.706535271298;4986.077575648516;5145.948540514584;5149.201873709581;5154.201098829687;5158.690478571298;3269.6893795089427;3370.2248482848836;3372.1063918061486;3416.794992300445;3418.435958066916;1126.4761885723096;1172.5016867831137;1208.6434501821896;1211.1037832004342;1222.7676741419102;10240.846387304196;10503.212259080523;10676.252662313642;10848.132149428106;10890.183236257313;4295.183915056129;4383.868483945486;4457.331400289918;4511.030128522383;4515.390422292949;8563.400561960727;8625.913740862636;8630.482171069756;8632.35257355701;8636.395452318277;5047.899042019139;5089.770960306795;5098.898022039539;5114.43741188469;5118.078745825787;4270.10327831159;4430.623397011225;4484.215262329079;4734.976325118421;4798.808047678123;3493.7205023598053;3532.452469867175;3603.4054381026835;3631.5097461842843;3640.8713298877433;978.4325091446943;1136.085451211766;1141.9412004818732;1143.6969203161168;1158.5192145259623;799.1688643810488;889.6886089868624;900.9819803603891;912.7170577121113;913.3741939347972;5389.730513474311;5427.294451721251;5541.085720856095;5725.885482290331;5754.683492407874;731.4002517935241;880.929959092562;882.955852207387;899.9865002024975;902.9413088149249;3653.8355773990234;3754.81225938702;3803.2963901678277;3817.4655548223272;3827.732058755916;9685.370450920667;9959.80080398388;9990.600375984879;9993;10007.399556026516;9934.50327483623;9942.15491683215;9956.80086398268;9961.651342202988;9998.400095994264;7323.377512911356;7542.622868856535;7607.35337496319;7664.386849052063;7837.3338266246865;4572.6570507212755;4660.741599768112;4705.529447055267;5508.14623733322;5686.147077938343;6340.714667839866;8008.439409241297;8966;9228.261739060994;9362.157405833508;8265.297449716601;8968.551572421222;9034.90965090367;9353.39202951806;9380.108889655472;7078.716851325889;7274.272572742683;7289.088863892013;7671.117821450604;7845.058592968652;7656.617169141632;7888.329491993015;7895.1315355309725;7895.6841726332605;8052.758417247492;6012.248468941382;6942.132233470817;7073.080499535027;7147.678354474165;7268.200497945328;6986.371226589631;6986.476014298846;6988.8951665724035;6996.125484314461;7055.576665400093;5340.252364669027;6281.623102613858;6346.714397852015;6362.650054247061;6370.840728981812;4921.680090794087;4941.851744447672;4972.676776009548;4993.525615066563;5061.164907790173;3993.880183594497;4969.801207951641;4981.726005069756;4994.575461085703;5005.574526165171 09:58:46 INFO - PROCESS | 1636 | |4;prototype.html;311.71010959807387;356.81802280836774;368.62400351641315;370.09880939911335;373.62823990129823;4574.4510658721;4709.788059537321;5166.5866730661555;5185.896282074356;5242.6330156889035;4867.926981095277;4998.400191976959;5006.220303670732;5010.423801262856;5061.367329083864;3177.952330715041;3955.6068578510017;4001.7198796084285;4010.8195131219163;4040.9797951010205;3711.888643340704;3874.532293597229;3994.500687414073;4082.101937573729;4086.9182616347657;3695.353313170198;3911.2568611963634;3979.5622481527007;4010.398440233974;4072.7149099563044;3974.741641793303;4027.9597204027878;4030.879073627796;4046.615571520701;4066.6949978751563;3480.8687176667686;3595.9751470830947;3606.296772129436;3609.3322735294055;3613.7470377073614;4366.432363792686;4438.866833994985;4886.804527818883;5026.522480364396;5040.495950404967;4276.486821581399;4625.722456652578;4665.206914824488;4666.300054991779;4685.461171965234;4780.402449693788;5418.539424148952;5457.863553411157;5476.603466116155;5479.698616576086;3982.4424580558457;3988.860389886369;3994.141259629165;3994.6404823565726;4009.4186342980397;4727.858164255078;5362.865928351784;5689.345725241567;5712;5714.942850571482;3633.6562916918438;4057.350823868167;4100.569440208735;4101.200265948111;4127.071408932966;3557.7331700122595;3681.0981309579324;4161;4166.979165104155;4191.161767646483;4099.7745124017865;4170.582941705805;4417.271150260235;4452.4211852458975;4707.83522576715;4900.215965445583;5182.922256166161;5444.537214336826;5482.5613950883835;5505.284313039279;1108.0027974822724;1110.4836251143256;1172.3493461129156;1242.6020726682482;1249.8062800265973;807.9030516337959;834.7996480844554;849.902261239953;856.7662565905201;867.3217324477157;3931.567527571965;3985.720999530063;4387.288957306653;4726.291056341576;4835.292236347586;4875.561199492063;5305.62860599762;5314.306556942965;5346.631622078144;5362.302900622972;4014.297497937908;4691.296305554194;4816.8554943351755;4828.830990915301;4867.3915760529935;2547.115648635845;2569.5503286924713;2646.8411895286345;2767.9446411071667;2778.402643431672;4882.8779280517565;5267.472432994466;5400.894485670755;5474.671519708831;5492.5605951523785;4526.864194074183;5224.843254702365;5382.461753824587;5509.283793106871;5767.798127065616;4921.581665558325;5346.732663366894;5769.826905192767;5854.326752423441;5860;5128.487151284841;5137.715571107223;5292.417834038176;5677.517411019944;5703.401142879938;5133.435322114639;5572.359178694502;5574.414686457863;5582.972085139549;5609.831705048855;4792.832250871203;5042.344495215599;5113;5829.970850145722;5851.970740146272;3392.966070339364;3663.4247273671513;3735.9066023349633;3807.5811660717854;3817.732758706864;5313.840584782462;5532.474414930575;5537.667739935617;5538.695371754592;5647.661140331593;4821.397325334333;5429.945700542944;5450.809221677222;5457.508824205841;5469.288992430958;4708.411448568929;4857.231378114441;5318.97340513295;5438.23864658956;5458.732323308027;4959.528844759742;5075.543201111917;5334.666333416646;5372.140457526777;5382.9275728843895;4400.933985990148;4623.352730617784;5340.465953404784;5342.103553238544;5414.529637083647;4768.856934291976;5061.569766569883;5335.786568537215;5347.946520534745;5364.946350536601 09:58:46 INFO - PROCESS | 1636 | |5;yui.html;733.1641928201848;767.9325737225255;809.3363441977577;810.4245985350401;822.4284122534837;14084.225367604779;15622.218889055544;15712.214389280547;16974.83025169748;17889.463316100537;15926.761098583502;16431.342746290164;16737.995720256793;17037.82962170378;17067.40264090757;864.4553931023446;879.2702057292464;883.730462209026;887.0419946457843;887.2635712358759;701.6246308225101;886.4636894678723;887.3744010472634;888.7244954064229;890.0609856601283;751.4777229825273;754.8641244576002;934.6495064350869;946.6923249943761;947.2658689515612;908.2416182487631;926.1294383279695;928.4429342394577;929.9956047468718;933.4306073295285;545.370097537345;685.674304705266;688.0230073295882;691.6991108867659;695.5305169010898;12604.432800523999;15112.848871511254;15609.609759755984;15641.217939102999;15957.601059973478;11510.769784604345;13947.093438926539;13994.230317332542;14023.368948397347;14039.508617198451;11362.318260904372;11372.60195893148;11431.771364572745;11546.53813847445;11740.11949103812;3658.2317713279976;3840.519935008124;3870.2066076454257;3871.748336358156;3884.7863367514774;104508.47745761223;110071.89928100696;111523;111701.32448013205;112146;10750.354978701303;10761.730956726144;10836.67489975302;10873.565057397775;11263.94368028171;21678.674819877713;21683.34949951504;21694;21794.455138621342;22611.321660350215;7432.702691892264;7501.962490187514;7658.502197357266;7707.499012564165;7711.9228807712325;3864.2851072551666;4151.003759097855;4252.574742525722;4294.634956028773;4426.313921342197;3338.432466480704;3542.5217595624777;3611.4582812577796;3649.489071529988;3658.725595580289;503.6902305082348;552.2958228259002;600.8227572866015;609.3358239518857;614.5483069943588;650.4276236911487;660.3561527510639;675.722953589036;682.6620822692798;706.2231545300128;9500.334976551576;10081.798364032826;10199.796004079877;10363.896361036444;10380.42907640072;648.9919224394963;672.9461643068543;679.9048133261348;680.1735890892589;686.9793906182723;3424.400729872262;4621.768911554409;4934.150061353952;4939.777710003059;4947.784447829293;81016;83431.16568834355;90795;101031;102375.97624023813;87518.0350139581;87712.56143719368;88261.55869220741;88523.11476885277;90480;63633.09100726985;78633;78806.81789773158;79000.60499697465;80140.19859801444;8051.83896322082;9456.574454149717;9549.188318992685;9584.137427631547;9591.808163836824;32762.180945476553;50037.74981125071;51635.741821290656;52250.811091836724;52767.472325276256;69356.6532167356;69628.99266584408;70059.59880802457;70191;71652.28347716456;11419.942900285445;11578.189526733047;11791.941040294743;11838.408079596158;11999.940000299943;11722.58970936013;12745.17156384832;12911.5480958166;13030.804537932128;13327.733445330845;10249.74375640615;11826.46780894879;11851.170418070997;11919.761604767682;12311.630651080482;9716.514174291116;11931.582394616147;11945.16383853122;12072.75854482888;12479.56321528742;11471.197016208785;11600.6519804406;11913.702157445787;12111.970482509085;12276.693082673004;8274.669013239643;8856.601452934763;10070.693414087831;10364.689059328231;10914.290571112851;9943.241329090368;10006;10020.599176033169;10023.448710321003;10121.291509594257 09:58:46 INFO - PROCESS | 1636 | __end_tp_report 09:58:46 INFO - PROCESS | 1636 | __start_cc_report 09:58:46 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,2716 09:58:46 INFO - PROCESS | 1636 | __end_cc_report 09:58:46 INFO - PROCESS | 1636 | __startTimestamp1485971926269__endTimestamp 09:58:46 INFO - PROCESS | 1636 | 09:58:46 INFO - PROCESS | 1636 | ------- Summary: start ------- 09:58:46 INFO - PROCESS | 1636 | Number of tests: 6 09:58:46 INFO - PROCESS | 1636 | 09:58:46 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5658.57 Median:5835.45 stddev:658.00 (11.3%) stddev-sans-first:634.00 09:58:46 INFO - PROCESS | 1636 | Values: 3323.2 3339.4 3345.9 3351.3 3393.5 5511.2 5558.0 5595.1 5638.8 5653.6 5567.0 5623.7 5707.4 5709.5 5735.4 4139.5 5090.1 5123.2 5168.7 5184.2 5149.3 5246.3 5263.8 5270.5 5339.5 5077.1 5234.4 5246.7 5253.1 5272.4 5088.6 5120.3 5148.7 5163.0 5171.0 5104.1 5122.5 5147.0 5148.0 5172.7 4609.6 5522.6 5624.6 5633.8 5636.1 5179.6 6046.4 6101.9 6105.8 6146.0 6158.2 6201.6 6213.2 6227.8 6290.8 5786.2 5791.2 5793.8 5801.6 5827.6 6232.0 6236.6 6271.8 6284.0 6312.6 5976.1 6019.6 6023.6 6080.8 6134.3 6101.4 6185.6 6231.6 6235.2 6259.6 6061.5 6069.1 6141.4 6159.3 6159.8 6202.3 6219.4 6221.9 6222.1 6231.0 4759.6 4873.5 4877.7 4884.0 4908.1 4323.5 4333.7 4339.1 4362.0 4366.3 5798.4 5808.7 5823.4 5835.4 5845.8 5175.5 6150.8 6277.1 6280.4 6284.7 5608.2 5647.7 5683.4 5749.7 5777.1 5308.1 5353.9 5355.9 5363.4 5385.3 6308.0 6325.6 6332.9 6336.7 6346.3 6116.5 6213.5 6231.6 6249.8 6262.2 6252.1 6268.1 6269.8 6302.1 6318.7 6168.5 6169.3 6177.6 6196.6 6217.4 4816.7 5113.9 6180.3 6183.3 6250.6 4835.7 5853.5 5901.3 5978.2 6016.1 6004.8 6018.0 6027.6 6057.1 6086.7 5692.3 5790.2 5794.2 5842.8 5864.6 5926.1 5995.1 6021.6 6058.8 6065.1 5832.5 5835.8 5842.9 5849.3 5856.0 09:58:46 INFO - PROCESS | 1636 | 09:58:46 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:67670.78 Median:38478.52 stddev:144742.86 (376.2%) stddev-sans-first:145060.94 09:58:46 INFO - PROCESS | 1636 | Values: 462.7 468.3 481.1 503.5 520.5 36053.5 39033.0 41063.8 43319.4 43373.6 41608.8 42355.4 42422.4 42599.8 42726.8 8256.2 8987.1 9321.0 9322.4 9338.4 8314.5 11005.6 11245.3 11278.4 11343.1 11052.2 11102.7 11112.2 11125.7 11159.2 8308.9 8514.6 10890.4 10974.1 11010.4 8335.0 8396.3 8407.9 10856.9 11103.3 40825.6 41103.0 41128.0 41147.4 41327.4 4014.9 4021.8 4022.3 4087.3 4192.4 30684.0 30754.0 30815.1 30854.8 30930.2 2896.6 3089.1 3089.7 3149.0 3159.4 477736.0 477780.0 479702.0 479883.0 481856.6 57974.7 58314.7 58430.1 58500.7 58528.0 60701.0 60777.0 60781.4 60784.7 60829.7 15480.5 15757.5 15767.8 15784.8 15807.8 11038.6 11299.0 11300.0 12284.5 12369.6 14946.3 15195.9 15233.5 15536.3 15609.0 4891.0 5493.2 5555.5 6193.9 6213.9 857.6 861.8 864.5 873.7 881.1 29676.6 29706.1 30087.5 30306.4 30520.0 946.3 1042.6 1082.5 1099.8 1160.4 15389.2 15422.0 15555.3 15730.4 15824.4 610017.0 611400.0 611671.0 613612.0 615089.0 58591.0 58838.7 58902.7 58935.0 58941.7 1389.9 1418.0 1447.8 1450.7 1459.6 24878.6 29250.3 29273.3 29734.9 30249.4 49246.5 49569.8 49603.8 49826.5 49859.0 523893.0 526564.0 527039.0 530757.0 530863.0 37416.8 38066.9 38353.6 38480.6 38689.6 38001.6 41469.8 45125.5 45688.5 46545.0 31755.7 37084.4 37268.0 38293.8 40072.8 30324.7 37865.4 37993.8 38293.6 39809.6 37624.8 37632.6 37687.0 38254.2 38476.4 29961.7 31389.4 34073.0 34384.8 34729.7 20034.8 24925.1 24960.8 25222.0 25739.9 09:58:46 INFO - PROCESS | 1636 | 09:58:46 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:40063.24 Median:21903.07 stddev:123123.09 (562.1%) stddev-sans-first:123432.46 09:58:46 INFO - PROCESS | 1636 | Values: 394.9 395.7 397.4 405.4 424.1 19402.2 19726.3 20880.4 20918.9 20976.0 20960.4 20978.5 21032.8 21320.9 21326.3 5631.5 5992.1 6054.6 6057.4 6063.5 5784.7 5872.0 5934.4 6048.1 6048.6 5804.0 5887.8 5904.7 5906.5 5907.1 5655.8 5873.2 5883.3 5914.1 5997.0 5232.1 5241.8 5244.4 5244.4 5245.7 24976.6 25407.0 25792.7 25998.9 26241.6 2681.3 2699.7 2709.9 2717.0 2721.3 15282.2 15439.3 15547.5 15642.0 15858.5 1781.5 1865.6 1870.8 1877.6 1883.8 120157.2 125130.0 125332.0 125569.4 126205.4 11349.4 11390.9 11498.5 11587.5 11764.6 12797.0 13356.2 14490.3 14518.4 14524.4 8004.3 8357.2 8562.7 8683.5 8704.0 7478.5 8378.6 8650.3 8669.9 8795.2 6182.5 7199.0 7202.2 7267.8 7304.1 1628.8 1892.9 1935.4 1936.0 1943.6 1059.6 1066.5 1068.4 1081.8 1118.1 22479.9 22652.3 22660.0 22901.9 23214.7 1072.7 1079.7 1100.7 1104.2 1122.4 11104.3 11244.0 11486.8 11750.7 11767.6 726618.2 729882.0 743476.0 753082.0 755469.0 141941.0 141944.3 145905.0 146274.0 149082.3 888.6 1007.5 1027.3 1031.2 1031.2 14041.9 17578.9 19718.4 19733.0 19776.6 53530.5 54906.1 56317.0 57848.1 58063.7 67471.0 81006.0 81933.2 82488.0 82563.3 6319.4 6925.6 8024.6 8174.8 8361.5 8157.8 13261.7 13324.8 13421.7 13936.9 10564.6 10729.8 10860.9 11189.7 11479.6 9319.8 11357.9 11372.8 11572.5 11721.3 9639.5 11602.7 11970.6 12028.9 12161.5 6309.7 7478.7 7554.4 7733.9 7812.3 11111.1 11474.3 11503.1 11853.7 11889.3 09:58:46 INFO - PROCESS | 1636 | 09:58:46 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5290.01 Median:5151.70 stddev:2894.97 (56.2%) stddev-sans-first:2888.01 09:58:46 INFO - PROCESS | 1636 | Values: 1357.6 1424.2 1424.9 1425.8 1431.7 7713.5 8022.9 8041.2 8046.2 8064.4 7340.1 7613.8 7625.5 7959.9 8137.7 1934.9 2256.6 2299.7 2301.9 2307.3 1915.0 2252.5 2297.3 2306.7 2310.9 2013.1 2094.1 2203.3 2261.2 2284.5 2282.0 2288.7 2290.9 2295.2 2303.5 2225.1 2282.6 2301.0 2307.8 2314.3 8293.4 8363.9 8379.1 8382.8 8384.7 4986.1 5145.9 5149.2 5154.2 5158.7 3269.7 3370.2 3372.1 3416.8 3418.4 1126.5 1172.5 1208.6 1211.1 1222.8 10240.8 10503.2 10676.3 10848.1 10890.2 4295.2 4383.9 4457.3 4511.0 4515.4 8563.4 8625.9 8630.5 8632.4 8636.4 5047.9 5089.8 5098.9 5114.4 5118.1 4270.1 4430.6 4484.2 4735.0 4798.8 3493.7 3532.5 3603.4 3631.5 3640.9 978.4 1136.1 1141.9 1143.7 1158.5 799.2 889.7 901.0 912.7 913.4 5389.7 5427.3 5541.1 5725.9 5754.7 731.4 880.9 883.0 900.0 902.9 3653.8 3754.8 3803.3 3817.5 3827.7 9685.4 9959.8 9990.6 9993.0 10007.4 9934.5 9942.2 9956.8 9961.7 9998.4 7323.4 7542.6 7607.4 7664.4 7837.3 4572.7 4660.7 4705.5 5508.1 5686.1 6340.7 8008.4 8966.0 9228.3 9362.2 8265.3 8968.6 9034.9 9353.4 9380.1 7078.7 7274.3 7289.1 7671.1 7845.1 7656.6 7888.3 7895.1 7895.7 8052.8 6012.2 6942.1 7073.1 7147.7 7268.2 6986.4 6986.5 6988.9 6996.1 7055.6 5340.3 6281.6 6346.7 6362.7 6370.8 4921.7 4941.9 4972.7 4993.5 5061.2 3993.9 4969.8 4981.7 4994.6 5005.6 09:58:46 INFO - PROCESS | 1636 | 09:58:46 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4382.34 Median:4832.06 stddev:1313.81 (27.2%) stddev-sans-first:1281.47 09:58:46 INFO - PROCESS | 1636 | Values: 311.7 356.8 368.6 370.1 373.6 4574.5 4709.8 5166.6 5185.9 5242.6 4867.9 4998.4 5006.2 5010.4 5061.4 3178.0 3955.6 4001.7 4010.8 4041.0 3711.9 3874.5 3994.5 4082.1 4086.9 3695.4 3911.3 3979.6 4010.4 4072.7 3974.7 4028.0 4030.9 4046.6 4066.7 3480.9 3596.0 3606.3 3609.3 3613.7 4366.4 4438.9 4886.8 5026.5 5040.5 4276.5 4625.7 4665.2 4666.3 4685.5 4780.4 5418.5 5457.9 5476.6 5479.7 3982.4 3988.9 3994.1 3994.6 4009.4 4727.9 5362.9 5689.3 5712.0 5714.9 3633.7 4057.4 4100.6 4101.2 4127.1 3557.7 3681.1 4161.0 4167.0 4191.2 4099.8 4170.6 4417.3 4452.4 4707.8 4900.2 5182.9 5444.5 5482.6 5505.3 1108.0 1110.5 1172.3 1242.6 1249.8 807.9 834.8 849.9 856.8 867.3 3931.6 3985.7 4387.3 4726.3 4835.3 4875.6 5305.6 5314.3 5346.6 5362.3 4014.3 4691.3 4816.9 4828.8 4867.4 2547.1 2569.6 2646.8 2767.9 2778.4 4882.9 5267.5 5400.9 5474.7 5492.6 4526.9 5224.8 5382.5 5509.3 5767.8 4921.6 5346.7 5769.8 5854.3 5860.0 5128.5 5137.7 5292.4 5677.5 5703.4 5133.4 5572.4 5574.4 5583.0 5609.8 4792.8 5042.3 5113.0 5830.0 5852.0 3393.0 3663.4 3735.9 3807.6 3817.7 5313.8 5532.5 5537.7 5538.7 5647.7 4821.4 5429.9 5450.8 5457.5 5469.3 4708.4 4857.2 5319.0 5438.2 5458.7 4959.5 5075.5 5334.7 5372.1 5382.9 4400.9 4623.4 5340.5 5342.1 5414.5 4768.9 5061.6 5335.8 5347.9 5364.9 09:58:46 INFO - PROCESS | 1636 | 09:58:46 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:19975.92 Median:4201.79 stddev:28979.84 (689.7%) stddev-sans-first:29025.12 09:58:46 INFO - PROCESS | 1636 | Values: 733.2 767.9 809.3 810.4 822.4 14084.2 15622.2 15712.2 16974.8 17889.5 15926.8 16431.3 16738.0 17037.8 17067.4 864.5 879.3 883.7 887.0 887.3 701.6 886.5 887.4 888.7 890.1 751.5 754.9 934.6 946.7 947.3 908.2 926.1 928.4 930.0 933.4 545.4 685.7 688.0 691.7 695.5 12604.4 15112.8 15609.6 15641.2 15957.6 11510.8 13947.1 13994.2 14023.4 14039.5 11362.3 11372.6 11431.8 11546.5 11740.1 3658.2 3840.5 3870.2 3871.7 3884.8 104508.5 110071.9 111523.0 111701.3 112146.0 10750.4 10761.7 10836.7 10873.6 11263.9 21678.7 21683.3 21694.0 21794.5 22611.3 7432.7 7502.0 7658.5 7707.5 7711.9 3864.3 4151.0 4252.6 4294.6 4426.3 3338.4 3542.5 3611.5 3649.5 3658.7 503.7 552.3 600.8 609.3 614.5 650.4 660.4 675.7 682.7 706.2 9500.3 10081.8 10199.8 10363.9 10380.4 649.0 672.9 679.9 680.2 687.0 3424.4 4621.8 4934.2 4939.8 4947.8 81016.0 83431.2 90795.0 101031.0 102376.0 87518.0 87712.6 88261.6 88523.1 90480.0 63633.1 78633.0 78806.8 79000.6 80140.2 8051.8 9456.6 9549.2 9584.1 9591.8 32762.2 50037.7 51635.7 52250.8 52767.5 69356.7 69629.0 70059.6 70191.0 71652.3 11419.9 11578.2 11791.9 11838.4 11999.9 11722.6 12745.2 12911.5 13030.8 13327.7 10249.7 11826.5 11851.2 11919.8 12311.6 9716.5 11931.6 11945.2 12072.8 12479.6 11471.2 11600.7 11913.7 12112.0 12276.7 8274.7 8856.6 10070.7 10364.7 10914.3 9943.2 10006.0 10020.6 10023.4 10121.3 09:58:46 INFO - PROCESS | 1636 | -------- Summary: end -------- 09:58:46 INFO - PROCESS | 1636 | 09:58:46 INFO - TEST-INFO | 1636: exit 0 09:58:46 INFO - TEST-OK | dromaeo_css | took 1119080ms 09:58:46 INFO - TEST-START | kraken 09:58:46 INFO - operating with platform_type : mac_ 09:58:46 INFO - Initialising browser for kraken test... 09:58:46 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpbiWHAV/profile http://localhost:49241/getInfo.html) 09:58:48 INFO - PROCESS | 1646 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 09:58:49 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 09:58:49 INFO - PROCESS | 1646 | colorDepth:24 09:58:49 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 09:58:49 INFO - PROCESS | 1646 | __metrics 09:58:49 INFO - PROCESS | 1646 | 1485971929223 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 09:58:49 INFO - PROCESS | 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:58:49 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:58:49 INFO - TEST-INFO | 1646: exit 0 09:58:49 INFO - Browser initialized. 09:58:49 INFO - Running cycle 1/1 for kraken test... 09:58:49 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpbiWHAV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:59:01 INFO - PROCESS | 1647 | RSS: Main: 237338624 09:59:01 INFO - PROCESS | 1647 | 09:59:04 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 09:59:05 INFO - PROCESS | 1647 | RSS: Main: 289193984 09:59:05 INFO - PROCESS | 1647 | 09:59:08 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 09:59:08 INFO - PROCESS | 1647 | RSS: Main: 290603008 09:59:08 INFO - PROCESS | 1647 | 09:59:12 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 09:59:12 INFO - PROCESS | 1647 | RSS: Main: 382775296 09:59:12 INFO - PROCESS | 1647 | 09:59:14 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 09:59:15 INFO - PROCESS | 1647 | RSS: Main: 284610560 09:59:15 INFO - PROCESS | 1647 | 09:59:17 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:59:18 INFO - PROCESS | 1647 | RSS: Main: 287944704 09:59:18 INFO - PROCESS | 1647 | 09:59:20 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 09:59:21 INFO - PROCESS | 1647 | RSS: Main: 330956800 09:59:21 INFO - PROCESS | 1647 | 09:59:24 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 09:59:24 INFO - PROCESS | 1647 | RSS: Main: 332898304 09:59:24 INFO - PROCESS | 1647 | 09:59:27 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 09:59:28 INFO - PROCESS | 1647 | RSS: Main: 329977856 09:59:28 INFO - PROCESS | 1647 | 09:59:29 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:59:30 INFO - PROCESS | 1647 | RSS: Main: 306929664 09:59:30 INFO - PROCESS | 1647 | 09:59:32 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:59:33 INFO - PROCESS | 1647 | RSS: Main: 312115200 09:59:33 INFO - PROCESS | 1647 | 09:59:35 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:59:35 INFO - PROCESS | 1647 | RSS: Main: 314028032 09:59:35 INFO - PROCESS | 1647 | 09:59:38 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:59:39 INFO - PROCESS | 1647 | RSS: Main: 331194368 09:59:39 INFO - PROCESS | 1647 | 09:59:42 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:59:43 INFO - PROCESS | 1647 | RSS: Main: 325103616 09:59:43 INFO - PROCESS | 1647 | 09:59:44 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 09:59:45 INFO - PROCESS | 1647 | RSS: Main: 370511872 09:59:45 INFO - PROCESS | 1647 | 09:59:45 INFO - PROCESS | 1647 | __start_tp_report 09:59:45 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 09:59:45 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 09:59:45 INFO - PROCESS | 1647 | |i|pagename|runs| 09:59:45 INFO - PROCESS | 1647 | |0;ai-astar;113;112;114;113;112;114;113;113;113;114 09:59:45 INFO - PROCESS | 1647 | |1;audio-beat-detection;138;108;109;141;112;108;110;109;128;107 09:59:45 INFO - PROCESS | 1647 | |2;audio-dft;185;183;181;183;185;183;181;181;184;184 09:59:45 INFO - PROCESS | 1647 | |3;audio-fft;82;59;73;113;88;82;63;58;74;60 09:59:45 INFO - PROCESS | 1647 | |4;audio-oscillator;90;71;84;69;68;69;85;67;69;82 09:59:45 INFO - PROCESS | 1647 | |5;imaging-gaussian-blur;88;85;88;86;87;84;88;85;87;84 09:59:45 INFO - PROCESS | 1647 | |6;imaging-darkroom;94;94;94;95;93;94;94;94;93;94 09:59:45 INFO - PROCESS | 1647 | |7;imaging-desaturate;86;85;87;84;85;84;88;85;87;84 09:59:45 INFO - PROCESS | 1647 | |8;json-parse-financial;58;57;58;57;58;57;57;58;58;58 09:59:45 INFO - PROCESS | 1647 | |9;json-stringify-tinderbox;65;78;68;75;71;69;70;71;72;71 09:59:45 INFO - PROCESS | 1647 | |10;stanford-crypto-aes;88;89;87;90;87;86;86;87;87;91 09:59:45 INFO - PROCESS | 1647 | |11;stanford-crypto-ccm;143;141;139;143;120;135;148;148;174;156 09:59:45 INFO - PROCESS | 1647 | |12;stanford-crypto-pbkdf2;176;174;177;173;173;175;175;171;171;173 09:59:45 INFO - PROCESS | 1647 | |13;stanford-crypto-sha256-iterative;66;67;67;66;68;67;67;68;67;68 09:59:45 INFO - PROCESS | 1647 | __end_tp_report 09:59:45 INFO - PROCESS | 1647 | __start_cc_report 09:59:45 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1983 09:59:45 INFO - PROCESS | 1647 | __end_cc_report 09:59:45 INFO - PROCESS | 1647 | __startTimestamp1485971985073__endTimestamp 09:59:45 INFO - PROCESS | 1647 | 09:59:45 INFO - PROCESS | 1647 | ------- Summary: start ------- 09:59:45 INFO - PROCESS | 1647 | Number of tests: 14 09:59:45 INFO - PROCESS | 1647 | 09:59:45 INFO - PROCESS | 1647 | [#0] ai-astar Cycles:10 Average:113.10 Median:113.00 stddev:0.74 (0.7%) stddev-sans-first:0.78 09:59:45 INFO - PROCESS | 1647 | Values: 113.0 112.0 114.0 113.0 112.0 114.0 113.0 113.0 113.0 114.0 09:59:45 INFO - PROCESS | 1647 | 09:59:45 INFO - PROCESS | 1647 | [#1] audio-beat-detection Cycles:10 Average:117.00 Median:111.00 stddev:13.34 (12.0%) stddev-sans-first:11.79 09:59:45 INFO - PROCESS | 1647 | Values: 138.0 108.0 109.0 141.0 112.0 108.0 110.0 109.0 128.0 107.0 09:59:45 INFO - PROCESS | 1647 | 09:59:45 INFO - PROCESS | 1647 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.50 stddev:1.56 (0.9%) stddev-sans-first:1.48 09:59:45 INFO - PROCESS | 1647 | Values: 185.0 183.0 181.0 183.0 185.0 183.0 181.0 181.0 184.0 184.0 09:59:45 INFO - PROCESS | 1647 | 09:59:45 INFO - PROCESS | 1647 | [#3] audio-fft Cycles:10 Average:75.20 Median:73.50 stddev:17.09 (23.3%) stddev-sans-first:17.95 09:59:45 INFO - PROCESS | 1647 | Values: 82.0 59.0 73.0 113.0 88.0 82.0 63.0 58.0 74.0 60.0 09:59:45 INFO - PROCESS | 1647 | 09:59:45 INFO - PROCESS | 1647 | [#4] audio-oscillator Cycles:10 Average:75.40 Median:76.50 stddev:8.76 (11.4%) stddev-sans-first:7.53 09:59:45 INFO - PROCESS | 1647 | Values: 90.0 71.0 84.0 69.0 68.0 69.0 85.0 67.0 69.0 82.0 09:59:45 INFO - PROCESS | 1647 | 09:59:45 INFO - PROCESS | 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:87.00 stddev:1.62 (1.9%) stddev-sans-first:1.58 09:59:45 INFO - PROCESS | 1647 | Values: 88.0 85.0 88.0 86.0 87.0 84.0 88.0 85.0 87.0 84.0 09:59:45 INFO - PROCESS | 1647 | 09:59:45 INFO - PROCESS | 1647 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:94.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 09:59:45 INFO - PROCESS | 1647 | Values: 94.0 94.0 94.0 95.0 93.0 94.0 94.0 94.0 93.0 94.0 09:59:45 INFO - PROCESS | 1647 | 09:59:45 INFO - PROCESS | 1647 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:85.50 stddev:1.43 (1.7%) stddev-sans-first:1.51 09:59:45 INFO - PROCESS | 1647 | Values: 86.0 85.0 87.0 84.0 85.0 84.0 88.0 85.0 87.0 84.0 09:59:45 INFO - PROCESS | 1647 | 09:59:45 INFO - PROCESS | 1647 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:58.00 stddev:0.52 (0.9%) stddev-sans-first:0.53 09:59:45 INFO - PROCESS | 1647 | Values: 58.0 57.0 58.0 57.0 58.0 57.0 57.0 58.0 58.0 58.0 09:59:45 INFO - PROCESS | 1647 | 09:59:45 INFO - PROCESS | 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.00 stddev:3.59 (5.1%) stddev-sans-first:3.08 09:59:45 INFO - PROCESS | 1647 | Values: 65.0 78.0 68.0 75.0 71.0 69.0 70.0 71.0 72.0 71.0 09:59:45 INFO - PROCESS | 1647 | 09:59:45 INFO - PROCESS | 1647 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:87.50 stddev:1.69 (1.9%) stddev-sans-first:1.79 09:59:45 INFO - PROCESS | 1647 | Values: 88.0 89.0 87.0 90.0 87.0 86.0 86.0 87.0 87.0 91.0 09:59:45 INFO - PROCESS | 1647 | 09:59:45 INFO - PROCESS | 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:145.50 stddev:14.00 (9.6%) stddev-sans-first:14.84 09:59:45 INFO - PROCESS | 1647 | Values: 143.0 141.0 139.0 143.0 120.0 135.0 148.0 148.0 174.0 156.0 09:59:45 INFO - PROCESS | 1647 | 09:59:45 INFO - PROCESS | 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.80 Median:174.50 stddev:1.99 (1.1%) stddev-sans-first:1.94 09:59:45 INFO - PROCESS | 1647 | Values: 176.0 174.0 177.0 173.0 173.0 175.0 175.0 171.0 171.0 173.0 09:59:45 INFO - PROCESS | 1647 | 09:59:45 INFO - PROCESS | 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:0.74 (1.1%) stddev-sans-first:0.67 09:59:45 INFO - PROCESS | 1647 | Values: 66.0 67.0 67.0 66.0 68.0 67.0 67.0 68.0 67.0 68.0 09:59:45 INFO - PROCESS | 1647 | -------- Summary: end -------- 09:59:45 INFO - PROCESS | 1647 | 09:59:45 INFO - TEST-INFO | 1647: exit 0 09:59:45 INFO - TEST-OK | kraken | took 59082ms 09:59:45 INFO - SUITE-END | took 1178s 09:59:46 INFO - Completed test suite (00:19:38) 09:59:46 INFO - javascript benchmark 09:59:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7194.956892073189, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3323.1858194742285, 3339.415602269603, 3345.933081338375, 3351.2962277921633, 3393.5418718472997, 5511.173324001392, 5557.972210138949, 5595.132754423071, 5638.802641907534, 5653.6325138866, 5566.972165139179, 5623.718814059301, 5707.429257074291, 5709.457601527859, 5735.426457354262, 4139.503259608851, 5090.05833920724, 5123.1546794778815, 5168.689878607295, 5184.222366644995, 5149.330587023682, 5246.265522826808, 5263.789448422059, 5270.4729527047375, 5339.5461385782255, 5077.0607437623785, 5234.371875374979, 5246.711430871301, 5253.106971814782, 5272.446393128724, 5088.567471764904, 5120.334356533627, 5148.6910785353, 5163.0190263849745, 5171.043356978933, 5104.081265372231, 5122.462141475129, 5146.996335714537, 5148.021875843578, 5172.689638621695, 4609.585137337655, 5522.641028333186, 5624.550035997151, 5633.80281690143, 5636.098224284095, 5179.585633149339, 6046.395360463963, 6101.938980610182, 6105.847353816146, 6146.0, 6158.199434073586, 6201.565890387675, 6213.1922850029205, 6227.813165605039, 6290.779822706229, 5786.1610066540115, 5791.189233507313, 5793.768249270024, 5801.593888427812, 5827.592068555203, 6232.043096568182, 6236.6258024518675, 6271.843203919847, 6284.0, 6312.589681670676, 5976.103584462366, 6019.60872543292, 6023.578349515492, 6080.75676972925, 6134.325224225332, 6101.359357267423, 6185.597936134137, 6231.563790534619, 6235.158253635701, 6259.561830671809, 6061.515078793686, 6069.089636554552, 6141.385861413823, 6159.3224745277985, 6159.84600384985, 6202.34875338083, 6219.40915613016, 6221.875562488724, 6222.12890195373, 6231.003039513656, 4759.595434388116, 4873.512648735098, 4877.6585639004925, 4884.0476107158765, 4908.092002979461, 4323.546027667113, 4333.674974376934, 4339.067100573392, 4361.978190109029, 4366.279563871989, 5798.420157984167, 5808.680522571299, 5823.359430462646, 5835.44563266489, 5845.766169353264, 5175.508326708957, 6150.846228844225, 6277.121203031579, 6280.371962803774, 6284.654344011124, 5608.242887210256, 5647.6893770843, 5683.403242659544, 5749.68376739275, 5777.133429985536, 5308.0710875596615, 5353.892922141613, 5355.86610334737, 5363.356397232357, 5385.299911011543, 6307.96846015767, 6325.588836725598, 6332.936670633235, 6336.7465301389275, 6346.333634968262, 6116.5106791457465, 6213.471854892204, 6231.594946328474, 6249.78125765596, 6262.15460912771, 6252.124702541739, 6268.091126786681, 6269.843253918506, 6302.086197501429, 6318.684065796784, 6168.506519478521, 6169.259688837368, 6177.62934223948, 6196.597221180609, 6217.409346112112, 4816.686915350491, 5113.923291150561, 6180.3201647817805, 6183.288921773874, 6250.562460627712, 4835.734034628129, 5853.502452291603, 5901.262342207224, 5978.163057172086, 6016.09758536223, 6004.849878753066, 6018.037114061729, 6027.60820546663, 6057.061155520902, 6086.7260973255425, 5692.288463942007, 5790.160426738184, 5794.159846822103, 5842.795502157404, 5864.560157988253, 5926.140709596998, 5995.130706047511, 6021.608595441282, 6058.818235452944, 6065.090236464389, 5832.475077243069, 5835.824925252249, 5842.853928651818, 5849.327327357409, 5855.970720146543], "value": 5617.670942723803, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.66919152805735, 468.25313624768484, 481.09313943217035, 503.514108884926, 520.5393226994111, 36053.45919811202, 39033.0, 41063.79468102663, 43319.35020974688, 43373.56626433735, 41608.79195604025, 42355.36466952998, 42422.36366454505, 42599.78700106495, 42726.786366068125, 8256.174382561756, 8987.10128987101, 9320.953395233048, 9322.440653560774, 9338.439693618371, 8314.542700151489, 11005.61480348184, 11245.269057511276, 11278.379689117095, 11343.092552595772, 11052.171087168454, 11102.666919992413, 11112.222144449892, 11125.666230013072, 11159.218854680175, 8308.875366869503, 8514.616842242114, 10890.401027943499, 10974.122070234362, 11010.39442830644, 8334.958325208396, 8396.286315663176, 8407.949006374203, 10856.891431085667, 11103.278286911325, 40825.57110501148, 41102.972425689295, 41128.0, 41147.38278925818, 41327.380089298684, 4014.9397759033636, 4021.8190181441614, 4022.3363145081025, 4087.2847251731127, 4192.371144328345, 30684.0, 30754.0, 30815.075547733602, 30854.84572577145, 30930.226744331572, 2896.6089577906923, 3089.1350421881907, 3089.675584063686, 3149.0395429394025, 3159.352332771776, 477736.0, 477780.0, 479702.0, 479883.0, 481856.5907170477, 57974.7101264491, 58314.7084264576, 58430.12354814681, 58500.707496462244, 58528.0, 60701.0, 60777.0, 60781.39218607757, 60784.656843874014, 60829.69585152046, 15480.5355839325, 15757.527274181793, 15767.842321576867, 15784.763228551581, 15807.841921580637, 11038.558457661604, 11299.0, 11299.983001529738, 12284.508619655293, 12369.567065152676, 14946.252687365588, 15195.860310476537, 15233.466828660943, 15536.300866461037, 15608.985415947927, 4890.963561624787, 5493.176023596413, 5555.527780138734, 6193.88510068192, 6213.875722485525, 857.6012154348258, 861.7716305179007, 864.5115509736978, 873.7422460374205, 881.0704706534667, 29676.554851677243, 29706.108816735534, 30087.548686769715, 30306.393872122433, 30519.964205905777, 946.2524605561667, 1042.629866397418, 1082.4587706146926, 1099.8130317845985, 1160.4487868262677, 15389.230538473255, 15422.0, 15555.30001149951, 15730.370785168465, 15824.446144385121, 610017.0, 611400.0, 611671.0, 613612.0, 615089.0, 58591.0, 58838.705806470694, 58902.705486472296, 58935.0, 58941.70529147327, 1389.9374528146257, 1417.9503717369842, 1447.7755947828105, 1450.7026059657956, 1459.5621313606089, 24878.55569532815, 29250.268743281587, 29273.268168295963, 29734.85132574323, 30249.395012099194, 49246.50753492419, 49569.75215124046, 49603.75198123986, 49826.50173498219, 49859.002819942674, 523893.0, 526564.0, 527039.0, 530757.0, 530863.0, 37416.771661778526, 38066.857994260215, 38353.61646383612, 38480.615193847705, 38689.6131038686, 38001.61998380091, 41469.755907322826, 45125.54874451213, 45688.54311456843, 46545.0, 31755.682443176196, 37084.44373334348, 37268.0, 38293.80853095828, 40072.79963600163, 30324.69675303219, 37865.43201851919, 37993.810030949666, 38293.617063829006, 39809.60666376663, 37624.811875940446, 37632.623673762915, 37687.0, 38254.23491530209, 38476.42285365778, 29961.700382995892, 31389.372212556078, 34073.0, 34384.82807585946, 34729.65270347264, 20034.799652003294, 24925.133609635122, 24960.750392495844, 25221.991120355713, 25739.871300643375], "value": 19718.209667512245, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.91706741584267, 395.6775228189024, 397.39199025491007, 405.43846772220473, 424.0670524845339, 19402.22391104354, 19726.309579164714, 20880.37358879236, 20918.89540552297, 20975.951202439894, 20960.37118886432, 20978.475538111554, 21032.789672103274, 21320.89339553304, 21326.253581124663, 5631.549476041917, 5992.131140984555, 6054.6367217966845, 6057.363976782441, 6063.514918806485, 5784.652920824743, 5872.0, 5934.43622855828, 6048.062550304688, 6048.576599638005, 5804.013317735974, 5887.793927212549, 5904.7047647617665, 5906.468417842393, 5907.0548712206055, 5655.802046928358, 5873.207117039209, 5883.264591926009, 5914.053751399755, 5997.0, 5232.084385232607, 5241.816536421207, 5244.423113457517, 5244.423113457517, 5245.6852588844795, 24976.62628555429, 25406.98372065115, 25792.74207257941, 25998.87000564985, 26241.606375904375, 2681.3028612560875, 2699.6760388753473, 2709.891604335824, 2716.9728302717117, 2721.319670082479, 15282.159481228528, 15439.30523126462, 15547.455839045691, 15642.0, 15858.52424427269, 1781.5100847266976, 1865.5615930256379, 1870.7661542307212, 1877.6296547696782, 1883.783364913025, 120157.19764203543, 125130.0, 125332.0, 125569.37215313865, 126205.36897315546, 11349.375784331776, 11390.886091138982, 11498.48256828428, 11587.536498540132, 11764.647060588197, 12797.0, 13356.198628082346, 14490.347934342979, 14518.419263229353, 14524.419023238952, 8004.319632831274, 8357.206065423776, 8562.700305489278, 8683.478991260545, 8704.04255531891, 7478.504299140193, 8378.581070946428, 8650.264727498106, 8669.913300867036, 8795.164459376349, 6182.474489668339, 7199.0, 7202.171750248684, 7267.818304542323, 7304.123505179413, 1628.7964004499438, 1892.8674992750375, 1935.380678182968, 1935.9806401936082, 1943.5821298420908, 1059.6185373265619, 1066.5200659703164, 1068.3536460441321, 1081.7998670245872, 1118.139032944628, 22479.88760056189, 22652.29470827965, 22659.97540762369, 22901.88549057244, 23214.65178022331, 1072.694282129605, 1079.681493959284, 1100.708312297242, 1104.1553211792984, 1122.405125283601, 11104.333739975627, 11244.0, 11486.770264594661, 11750.735917776849, 11767.588134415426, 726618.2014539572, 729882.0, 743476.0, 753082.0, 755469.0, 141941.0, 141944.29027855, 145905.0, 146274.0, 149082.25458872635, 888.644542183119, 1007.5214273220162, 1027.3476342522404, 1031.2059714020163, 1031.2162756305113, 14041.859581404055, 17578.91210543939, 19718.40844774659, 19733.02669733015, 19776.604467910274, 53530.46469535411, 54906.058454739265, 56317.0, 57848.13227801502, 58063.70968145132, 67471.0, 81006.0, 81933.18066819255, 82488.0, 82563.33085025985, 6319.431251187231, 6925.619090949845, 8024.558649274112, 8174.7956301092945, 8361.456505327134, 8157.836843262983, 13261.66845828862, 13324.800127998282, 13421.66445838862, 13936.860631393962, 10564.630237941636, 10729.785404292026, 10860.891391085988, 11189.72025699364, 11479.598214062467, 9319.767005824908, 11357.886421136014, 11372.772544549229, 11572.537098515964, 11721.296722196696, 9639.518024098626, 11602.709932251424, 11970.640880773592, 12028.917397434272, 12161.513539458323, 6309.684515774285, 7478.70085196586, 7554.357879580078, 7733.8839917400155, 7812.257835505618, 11111.055560277144, 11474.311541307214, 11503.137264705349, 11853.703657408634, 11889.28664280146], "value": 10061.685607704787, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.5859362894316, 1424.2095636921517, 1424.871761541461, 1425.8217722784646, 1431.6850292935553, 7713.460057795956, 8022.91977080229, 8041.195880411956, 8046.195380461951, 8064.435489515737, 7340.082489688789, 7613.771586852389, 7625.466217364773, 7959.880601790977, 8137.674493020272, 1934.941951741443, 2256.6050941085286, 2299.735530414007, 2301.9079236830507, 2307.3308740465245, 1914.9617007659838, 2252.549490101978, 2297.2993237062688, 2306.7116610423695, 2310.897083653875, 2013.0941076515626, 2094.099537199004, 2203.2508946958033, 2261.163369553268, 2284.5316710074394, 2282.0301371916953, 2288.7482376938524, 2290.8510946788406, 2295.21962532738, 2303.5047464794984, 2225.0765932138142, 2282.6005449046515, 2300.9654855177187, 2307.8038366738847, 2314.2941402872284, 8293.377996650215, 8363.916360836436, 8379.078301386842, 8382.790430239233, 8384.706535271298, 4986.077575648516, 5145.948540514584, 5149.201873709581, 5154.201098829687, 5158.690478571298, 3269.6893795089427, 3370.2248482848836, 3372.1063918061486, 3416.794992300445, 3418.435958066916, 1126.4761885723096, 1172.5016867831137, 1208.6434501821896, 1211.1037832004342, 1222.7676741419102, 10240.846387304196, 10503.212259080523, 10676.252662313642, 10848.132149428106, 10890.183236257313, 4295.183915056129, 4383.868483945486, 4457.331400289918, 4511.030128522383, 4515.390422292949, 8563.400561960727, 8625.913740862636, 8630.482171069756, 8632.35257355701, 8636.395452318277, 5047.899042019139, 5089.770960306795, 5098.898022039539, 5114.43741188469, 5118.078745825787, 4270.10327831159, 4430.623397011225, 4484.215262329079, 4734.976325118421, 4798.808047678123, 3493.7205023598053, 3532.452469867175, 3603.4054381026835, 3631.5097461842843, 3640.8713298877433, 978.4325091446943, 1136.085451211766, 1141.9412004818732, 1143.6969203161168, 1158.5192145259623, 799.1688643810488, 889.6886089868624, 900.9819803603891, 912.7170577121113, 913.3741939347972, 5389.730513474311, 5427.294451721251, 5541.085720856095, 5725.885482290331, 5754.683492407874, 731.4002517935241, 880.929959092562, 882.955852207387, 899.9865002024975, 902.9413088149249, 3653.8355773990234, 3754.81225938702, 3803.2963901678277, 3817.4655548223272, 3827.732058755916, 9685.370450920667, 9959.80080398388, 9990.600375984879, 9993.0, 10007.399556026516, 9934.50327483623, 9942.15491683215, 9956.80086398268, 9961.651342202988, 9998.400095994264, 7323.377512911356, 7542.622868856535, 7607.35337496319, 7664.386849052063, 7837.3338266246865, 4572.6570507212755, 4660.741599768112, 4705.529447055267, 5508.14623733322, 5686.147077938343, 6340.714667839866, 8008.439409241297, 8966.0, 9228.261739060994, 9362.157405833508, 8265.297449716601, 8968.551572421222, 9034.90965090367, 9353.39202951806, 9380.108889655472, 7078.716851325889, 7274.272572742683, 7289.088863892013, 7671.117821450604, 7845.058592968652, 7656.617169141632, 7888.329491993015, 7895.1315355309725, 7895.6841726332605, 8052.758417247492, 6012.248468941382, 6942.132233470817, 7073.080499535027, 7147.678354474165, 7268.200497945328, 6986.371226589631, 6986.476014298846, 6988.8951665724035, 6996.125484314461, 7055.576665400093, 5340.252364669027, 6281.623102613858, 6346.714397852015, 6362.650054247061, 6370.840728981812, 4921.680090794087, 4941.851744447672, 4972.676776009548, 4993.525615066563, 5061.164907790173, 3993.880183594497, 4969.801207951641, 4981.726005069756, 4994.575461085703, 5005.574526165171], "value": 4290.44832248145, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.71010959807387, 356.81802280836774, 368.62400351641315, 370.09880939911335, 373.62823990129823, 4574.4510658721, 4709.788059537321, 5166.5866730661555, 5185.896282074356, 5242.6330156889035, 4867.926981095277, 4998.400191976959, 5006.220303670732, 5010.423801262856, 5061.367329083864, 3177.952330715041, 3955.6068578510017, 4001.7198796084285, 4010.8195131219163, 4040.9797951010205, 3711.888643340704, 3874.532293597229, 3994.500687414073, 4082.101937573729, 4086.9182616347657, 3695.353313170198, 3911.2568611963634, 3979.5622481527007, 4010.398440233974, 4072.7149099563044, 3974.741641793303, 4027.9597204027878, 4030.879073627796, 4046.615571520701, 4066.6949978751563, 3480.8687176667686, 3595.9751470830947, 3606.296772129436, 3609.3322735294055, 3613.7470377073614, 4366.432363792686, 4438.866833994985, 4886.804527818883, 5026.522480364396, 5040.495950404967, 4276.486821581399, 4625.722456652578, 4665.206914824488, 4666.300054991779, 4685.461171965234, 4780.402449693788, 5418.539424148952, 5457.863553411157, 5476.603466116155, 5479.698616576086, 3982.4424580558457, 3988.860389886369, 3994.141259629165, 3994.6404823565726, 4009.4186342980397, 4727.858164255078, 5362.865928351784, 5689.345725241567, 5712.0, 5714.942850571482, 3633.6562916918438, 4057.350823868167, 4100.569440208735, 4101.200265948111, 4127.071408932966, 3557.7331700122595, 3681.0981309579324, 4161.0, 4166.979165104155, 4191.161767646483, 4099.7745124017865, 4170.582941705805, 4417.271150260235, 4452.4211852458975, 4707.83522576715, 4900.215965445583, 5182.922256166161, 5444.537214336826, 5482.5613950883835, 5505.284313039279, 1108.0027974822724, 1110.4836251143256, 1172.3493461129156, 1242.6020726682482, 1249.8062800265973, 807.9030516337959, 834.7996480844554, 849.902261239953, 856.7662565905201, 867.3217324477157, 3931.567527571965, 3985.720999530063, 4387.288957306653, 4726.291056341576, 4835.292236347586, 4875.561199492063, 5305.62860599762, 5314.306556942965, 5346.631622078144, 5362.302900622972, 4014.297497937908, 4691.296305554194, 4816.8554943351755, 4828.830990915301, 4867.3915760529935, 2547.115648635845, 2569.5503286924713, 2646.8411895286345, 2767.9446411071667, 2778.402643431672, 4882.8779280517565, 5267.472432994466, 5400.894485670755, 5474.671519708831, 5492.5605951523785, 4526.864194074183, 5224.843254702365, 5382.461753824587, 5509.283793106871, 5767.798127065616, 4921.581665558325, 5346.732663366894, 5769.826905192767, 5854.326752423441, 5860.0, 5128.487151284841, 5137.715571107223, 5292.417834038176, 5677.517411019944, 5703.401142879938, 5133.435322114639, 5572.359178694502, 5574.414686457863, 5582.972085139549, 5609.831705048855, 4792.832250871203, 5042.344495215599, 5113.0, 5829.970850145722, 5851.970740146272, 3392.966070339364, 3663.4247273671513, 3735.9066023349633, 3807.5811660717854, 3817.732758706864, 5313.840584782462, 5532.474414930575, 5537.667739935617, 5538.695371754592, 5647.661140331593, 4821.397325334333, 5429.945700542944, 5450.809221677222, 5457.508824205841, 5469.288992430958, 4708.411448568929, 4857.231378114441, 5318.97340513295, 5438.23864658956, 5458.732323308027, 4959.528844759742, 5075.543201111917, 5334.666333416646, 5372.140457526777, 5382.9275728843895, 4400.933985990148, 4623.352730617784, 5340.465953404784, 5342.103553238544, 5414.529637083647, 4768.856934291976, 5061.569766569883, 5335.786568537215, 5347.946520534745, 5364.946350536601], "value": 3972.4550676244016, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [733.1641928201848, 767.9325737225255, 809.3363441977577, 810.4245985350401, 822.4284122534837, 14084.225367604779, 15622.218889055544, 15712.214389280547, 16974.83025169748, 17889.463316100537, 15926.761098583502, 16431.342746290164, 16737.995720256793, 17037.82962170378, 17067.40264090757, 864.4553931023446, 879.2702057292464, 883.730462209026, 887.0419946457843, 887.2635712358759, 701.6246308225101, 886.4636894678723, 887.3744010472634, 888.7244954064229, 890.0609856601283, 751.4777229825273, 754.8641244576002, 934.6495064350869, 946.6923249943761, 947.2658689515612, 908.2416182487631, 926.1294383279695, 928.4429342394577, 929.9956047468718, 933.4306073295285, 545.370097537345, 685.674304705266, 688.0230073295882, 691.6991108867659, 695.5305169010898, 12604.432800523999, 15112.848871511254, 15609.609759755984, 15641.217939102999, 15957.601059973478, 11510.769784604345, 13947.093438926539, 13994.230317332542, 14023.368948397347, 14039.508617198451, 11362.318260904372, 11372.60195893148, 11431.771364572745, 11546.53813847445, 11740.11949103812, 3658.2317713279976, 3840.519935008124, 3870.2066076454257, 3871.748336358156, 3884.7863367514774, 104508.47745761223, 110071.89928100696, 111523.0, 111701.32448013205, 112146.0, 10750.354978701303, 10761.730956726144, 10836.67489975302, 10873.565057397775, 11263.94368028171, 21678.674819877713, 21683.34949951504, 21694.0, 21794.455138621342, 22611.321660350215, 7432.702691892264, 7501.962490187514, 7658.502197357266, 7707.499012564165, 7711.9228807712325, 3864.2851072551666, 4151.003759097855, 4252.574742525722, 4294.634956028773, 4426.313921342197, 3338.432466480704, 3542.5217595624777, 3611.4582812577796, 3649.489071529988, 3658.725595580289, 503.6902305082348, 552.2958228259002, 600.8227572866015, 609.3358239518857, 614.5483069943588, 650.4276236911487, 660.3561527510639, 675.722953589036, 682.6620822692798, 706.2231545300128, 9500.334976551576, 10081.798364032826, 10199.796004079877, 10363.896361036444, 10380.42907640072, 648.9919224394963, 672.9461643068543, 679.9048133261348, 680.1735890892589, 686.9793906182723, 3424.400729872262, 4621.768911554409, 4934.150061353952, 4939.777710003059, 4947.784447829293, 81016.0, 83431.16568834355, 90795.0, 101031.0, 102375.97624023813, 87518.0350139581, 87712.56143719368, 88261.55869220741, 88523.11476885277, 90480.0, 63633.09100726985, 78633.0, 78806.81789773158, 79000.60499697465, 80140.19859801444, 8051.83896322082, 9456.574454149717, 9549.188318992685, 9584.137427631547, 9591.808163836824, 32762.180945476553, 50037.74981125071, 51635.741821290656, 52250.811091836724, 52767.472325276256, 69356.6532167356, 69628.99266584408, 70059.59880802457, 70191.0, 71652.28347716456, 11419.942900285445, 11578.189526733047, 11791.941040294743, 11838.408079596158, 11999.940000299943, 11722.58970936013, 12745.17156384832, 12911.5480958166, 13030.804537932128, 13327.733445330845, 10249.74375640615, 11826.46780894879, 11851.170418070997, 11919.761604767682, 12311.630651080482, 9716.514174291116, 11931.582394616147, 11945.16383853122, 12072.75854482888, 12479.56321528742, 11471.197016208785, 11600.6519804406, 11913.702157445787, 12111.970482509085, 12276.693082673004, 8274.669013239643, 8856.601452934763, 10070.693414087831, 10364.689059328231, 10914.290571112851, 9943.241329090368, 10006.0, 10020.599176033169, 10023.448710321003, 10121.291509594257], "value": 7302.350058327574, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1431.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 114.0, 113.0, 112.0, 114.0, 113.0, 113.0, 113.0, 114.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 108.0, 109.0, 141.0, 112.0, 108.0, 110.0, 109.0, 128.0, 107.0], "value": 117.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 181.0, 183.0, 185.0, 183.0, 181.0, 181.0, 184.0, 184.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 73.0, 113.0, 88.0, 82.0, 63.0, 58.0, 74.0, 60.0], "value": 75.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 84.0, 69.0, 68.0, 69.0, 85.0, 67.0, 69.0, 82.0], "value": 75.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 86.0, 87.0, 84.0, 88.0, 85.0, 87.0, 84.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 93.0, 94.0, 94.0, 94.0, 93.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 84.0, 85.0, 84.0, 88.0, 85.0, 87.0, 84.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 58.0, 57.0, 58.0, 57.0, 57.0, 58.0, 58.0, 58.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 78.0, 68.0, 75.0, 71.0, 69.0, 70.0, 71.0, 72.0, 71.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 87.0, 90.0, 87.0, 86.0, 86.0, 87.0, 87.0, 91.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 141.0, 139.0, 143.0, 120.0, 135.0, 148.0, 148.0, 174.0, 156.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 174.0, 177.0, 173.0, 173.0, 175.0, 175.0, 171.0, 171.0, 173.0], "value": 173.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 66.0, 68.0, 67.0, 67.0, 68.0, 67.0, 68.0], "value": 67.1, "unit": "score"}]}]} 09:59:46 INFO - Return code: 0 09:59:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:59:46 ERROR - # TBPL SUCCESS # 09:59:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:59:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:59:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:59:46 INFO - 2017-02-01 09:59:46.387 screenresolution[1651:9587] starting screenresolution argv=screenresolution get 09:59:46 INFO - 2017-02-01 09:59:46.390 screenresolution[1651:9587] Display 0: 1600x1200x32@60 09:59:46 INFO - 2017-02-01 09:59:46.396 screenresolution[1652:9590] starting screenresolution argv=screenresolution list 09:59:46 INFO - Available Modes on Display 0 09:59:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:59:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:59:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:59:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:59:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:59:46 INFO - Intel Iris: 09:59:46 INFO - Chipset Model: Intel Iris 09:59:46 INFO - Type: GPU 09:59:46 INFO - Bus: Built-In 09:59:46 INFO - VRAM (Dynamic, Max): 1536 MB 09:59:46 INFO - Vendor: Intel (0x8086) 09:59:46 INFO - Device ID: 0x0a2e 09:59:46 INFO - Revision ID: 0x0009 09:59:46 INFO - Displays: 09:59:46 INFO - Display: 09:59:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:59:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:59:46 INFO - Display Serial Number: 1600x1200 60 09:59:46 INFO - Main Display: Yes 09:59:46 INFO - Mirror: Off 09:59:46 INFO - Online: Yes 09:59:46 INFO - Rotation: Supported 09:59:46 INFO - Return code: 0 09:59:46 INFO - Running post-action listener: _resource_record_post_action 09:59:46 INFO - [mozharness: 2017-02-01 17:59:46.638028Z] Finished run-tests step (success) 09:59:46 INFO - Running post-run listener: _resource_record_post_run 09:59:46 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 37852160; Write bytes: 555060736; Read time: 239; Write time: 1682 09:59:46 INFO - TinderboxPrint: CPU usage
53.7% 09:59:46 INFO - TinderboxPrint: I/O read bytes / time
37,852,160 / 239 09:59:46 INFO - TinderboxPrint: I/O write bytes / time
555,060,736 / 1,682 09:59:46 INFO - TinderboxPrint: CPU idle
2,224.8 (46.3%) 09:59:46 INFO - TinderboxPrint: CPU system
963.7 (20.1%) 09:59:46 INFO - TinderboxPrint: CPU user
1,617.2 (33.7%) 09:59:46 INFO - TinderboxPrint: Swap in / out
290,111,488 / 0 09:59:46 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 248740352; Write bytes: 242894848; Read time: 17899; Write time: 531 09:59:46 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 34415616; Write bytes: 298780160; Read time: 217; Write time: 1110 09:59:47 INFO - Running post-run listener: _upload_blobber_files 09:59:47 INFO - Blob upload gear active. 09:59:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:59:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:59:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:59:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:59:47 INFO - (blobuploader) - INFO - Open directory for files ... 09:59:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:59:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:59:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:48 INFO - (blobuploader) - INFO - Done attempting. 09:59:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:59:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:59:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:49 INFO - (blobuploader) - INFO - Done attempting. 09:59:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:59:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:59:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:50 INFO - (blobuploader) - INFO - Done attempting. 09:59:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:59:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:59:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:59:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:59:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:59:52 INFO - (blobuploader) - INFO - Done attempting. 09:59:52 INFO - (blobuploader) - INFO - Iteration through files over. 09:59:52 INFO - Return code: 0 09:59:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:59:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:59:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e65f95d1cb8dcdf32798ba0e941534007eaffd76fbd2e5516f5ef6052211052324b4820a34f5182480a1c603a865d15b7ea132682abc151161d604396f1758c9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65f36204f2367c187dd0ad443d745ff2775e1db75eb1aeb38124811b0f32d3ad444db69e7590af44ab810877e5887f2c7ccfd70e457eda67a79a9e25718b4007", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f0712f3f34e966a314bed793279fe8e62b3df556ec8962095fb9ac72e3ebea3a284ff1219ddfeb43a3fa296ddf90fa6a66120f2949d5fca99095fbfcf5c57c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eebc649523b2c60038f6a298e1f66047513298a2ea4ea18f645604a7f280507199d68ce504b6b929c74ddd2501c67e225bdadec62de9eafca75498b5b53ad2ab"} 09:59:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:59:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:59:52 INFO - Contents: 09:59:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e65f95d1cb8dcdf32798ba0e941534007eaffd76fbd2e5516f5ef6052211052324b4820a34f5182480a1c603a865d15b7ea132682abc151161d604396f1758c9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65f36204f2367c187dd0ad443d745ff2775e1db75eb1aeb38124811b0f32d3ad444db69e7590af44ab810877e5887f2c7ccfd70e457eda67a79a9e25718b4007", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f0712f3f34e966a314bed793279fe8e62b3df556ec8962095fb9ac72e3ebea3a284ff1219ddfeb43a3fa296ddf90fa6a66120f2949d5fca99095fbfcf5c57c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eebc649523b2c60038f6a298e1f66047513298a2ea4ea18f645604a7f280507199d68ce504b6b929c74ddd2501c67e225bdadec62de9eafca75498b5b53ad2ab"} 09:59:52 INFO - Running post-run listener: copy_logs_to_upload_dir 09:59:52 INFO - Copying logs to upload dir... 09:59:52 INFO - mkdir: /builds/slave/test/build/upload/logs 09:59:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.212049 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-01 09:59:52.896211) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-01 09:59:52.899295) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FJptP1e35K/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e65f95d1cb8dcdf32798ba0e941534007eaffd76fbd2e5516f5ef6052211052324b4820a34f5182480a1c603a865d15b7ea132682abc151161d604396f1758c9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65f36204f2367c187dd0ad443d745ff2775e1db75eb1aeb38124811b0f32d3ad444db69e7590af44ab810877e5887f2c7ccfd70e457eda67a79a9e25718b4007", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f0712f3f34e966a314bed793279fe8e62b3df556ec8962095fb9ac72e3ebea3a284ff1219ddfeb43a3fa296ddf90fa6a66120f2949d5fca99095fbfcf5c57c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eebc649523b2c60038f6a298e1f66047513298a2ea4ea18f645604a7f280507199d68ce504b6b929c74ddd2501c67e225bdadec62de9eafca75498b5b53ad2ab"} build_url:https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010901 build_url: 'https://queue.taskcluster.net/v1/task/Hpm-jWpmQvKx57s0mrEZdA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e65f95d1cb8dcdf32798ba0e941534007eaffd76fbd2e5516f5ef6052211052324b4820a34f5182480a1c603a865d15b7ea132682abc151161d604396f1758c9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/65f36204f2367c187dd0ad443d745ff2775e1db75eb1aeb38124811b0f32d3ad444db69e7590af44ab810877e5887f2c7ccfd70e457eda67a79a9e25718b4007", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9f0712f3f34e966a314bed793279fe8e62b3df556ec8962095fb9ac72e3ebea3a284ff1219ddfeb43a3fa296ddf90fa6a66120f2949d5fca99095fbfcf5c57c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eebc649523b2c60038f6a298e1f66047513298a2ea4ea18f645604a7f280507199d68ce504b6b929c74ddd2501c67e225bdadec62de9eafca75498b5b53ad2ab"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-01 09:59:52.933539) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 09:59:52.933876) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FJptP1e35K/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.ibEgzDUv9w/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005040 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-01 09:59:52.982707) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-01 09:59:52.983032) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-01 09:59:52.983368) ========= ========= Total master_lag: 0.18 =========