builder: pine_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0154 starttime: 1492581125.15 results: success (0) buildid: 20170418162120 builduid: d3c072e1c6b5477fb42de3f295a4c9a0 revision: e6f714bb5eb2b5d6aff241ae1c46044c3563de24 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-18 22:52:05.145839) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-18 22:52:05.146358) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-18 22:52:05.146652) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-18 22:52:05.173729) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 22:52:05.174119) ========= 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.PT3NkkqzUs/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.96K9bhmIlw/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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-04-18 22:52:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 28.1M=0s 2017-04-18 22:52:05 (28.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.141838 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 22:52:05.341474) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 22:52:05.341845) ========= 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.PT3NkkqzUs/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.96K9bhmIlw/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.028068 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 22:52:05.392597) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-18 22:52:05.393004) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev e6f714bb5eb2b5d6aff241ae1c46044c3563de24 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev e6f714bb5eb2b5d6aff241ae1c46044c3563de24 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PT3NkkqzUs/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.96K9bhmIlw/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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-04-18 22:52:05,549 truncating revision to first 12 chars 2017-04-18 22:52:05,549 Setting DEBUG logging. 2017-04-18 22:52:05,549 attempt 1/10 2017-04-18 22:52:05,549 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/e6f714bb5eb2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-18 22:52:06,640 unpacking tar archive at: pine-e6f714bb5eb2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.357377 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-18 22:52:06.775047) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-18 22:52:06.775661) ========= script_repo_revision: e6f714bb5eb2b5d6aff241ae1c46044c3563de24 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-18 22:52:06.776239) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-18 22:52:06.776519) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-18 22:52:06.796987) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-18 22:52:06.797521) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.PT3NkkqzUs/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.96K9bhmIlw/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 22:52:07 INFO - MultiFileLogger online at 20170418 22:52:07 in /builds/slave/test 22:52:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 22:52:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:52:07 INFO - {'append_to_log': False, 22:52:07 INFO - 'base_work_dir': '/builds/slave/test', 22:52:07 INFO - 'blob_upload_branch': 'Pine', 22:52:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:52:07 INFO - 'branch': 'Pine', 22:52:07 INFO - 'buildbot_json_path': 'buildprops.json', 22:52:07 INFO - 'config_files': ('talos/mac_config.py',), 22:52:07 INFO - 'default_actions': ('clobber', 22:52:07 INFO - 'read-buildbot-config', 22:52:07 INFO - 'download-and-extract', 22:52:07 INFO - 'populate-webroot', 22:52:07 INFO - 'create-virtualenv', 22:52:07 INFO - 'install', 22:52:07 INFO - 'run-tests'), 22:52:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:52:07 INFO - 'download_minidump_stackwalk': True, 22:52:07 INFO - 'download_symbols': 'ondemand', 22:52:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:52:07 INFO - 'tooltool.py': '/tools/tooltool.py', 22:52:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:52:07 INFO - '/tools/misc-python/virtualenv.py')}, 22:52:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:52:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:52:07 INFO - 'gecko_profile': False, 22:52:07 INFO - 'gecko_profile_interval': 0, 22:52:07 INFO - 'installer_path': 'installer.exe', 22:52:07 INFO - 'log_level': 'info', 22:52:07 INFO - 'log_name': 'talos', 22:52:07 INFO - 'log_to_console': True, 22:52:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:52:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:52:07 INFO - 'opt_config_files': (), 22:52:07 INFO - 'pip_index': False, 22:52:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:52:07 INFO - 'cmd': ('bash', 22:52:07 INFO - '-c', 22:52:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:52:07 INFO - 'enabled': True, 22:52:07 INFO - 'halt_on_failure': False, 22:52:07 INFO - 'name': 'check_screen_resolution'},), 22:52:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:52:07 INFO - 'cmd': ('bash', 22:52:07 INFO - '-c', 22:52:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:52:07 INFO - 'enabled': True, 22:52:07 INFO - 'halt_on_failure': False, 22:52:07 INFO - 'name': 'check_screen_resolution'},), 22:52:07 INFO - 'run_cmd_checks_enabled': True, 22:52:07 INFO - 'suite': 'dromaeojs', 22:52:07 INFO - 'system_bits': '32', 22:52:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:52:07 INFO - 'title': 't-yosemite-r7-0154', 22:52:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:52:07 INFO - 'use_talos_json': True, 22:52:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:52:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:52:07 INFO - 'work_dir': 'build'} 22:52:07 INFO - [mozharness: 2017-04-19 05:52:07.067193Z] Running clobber step. 22:52:07 INFO - Running pre-action listener: _resource_record_pre_action 22:52:07 INFO - Running main action method: clobber 22:52:07 INFO - rmtree: /builds/slave/test/build 22:52:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:52:09 INFO - Running post-action listener: _resource_record_post_action 22:52:09 INFO - [mozharness: 2017-04-19 05:52:09.687683Z] Finished clobber step (success) 22:52:09 INFO - [mozharness: 2017-04-19 05:52:09.687865Z] Running read-buildbot-config step. 22:52:09 INFO - Running pre-action listener: _resource_record_pre_action 22:52:09 INFO - Running main action method: read_buildbot_config 22:52:09 INFO - Using buildbot properties: 22:52:09 INFO - { 22:52:09 INFO - "project": "", 22:52:09 INFO - "product": "firefox", 22:52:09 INFO - "script_repo_revision": "default", 22:52:09 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs", 22:52:09 INFO - "repository": "", 22:52:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs", 22:52:09 INFO - "buildid": "20170418162120", 22:52:09 INFO - "pgo_build": "False", 22:52:09 INFO - "basedir": "/builds/slave/test", 22:52:09 INFO - "buildnumber": 8, 22:52:09 INFO - "slavename": "t-yosemite-r7-0154", 22:52:09 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 22:52:09 INFO - "platform": "yosemite_r7", 22:52:09 INFO - "scheduler": "tests-pine-macosx64-talos", 22:52:09 INFO - "branch": "pine", 22:52:09 INFO - "repo_path": "projects/pine", 22:52:09 INFO - "revision": "e6f714bb5eb2b5d6aff241ae1c46044c3563de24", 22:52:09 INFO - "stage_platform": "macosx64", 22:52:09 INFO - "builduid": "d3c072e1c6b5477fb42de3f295a4c9a0", 22:52:09 INFO - "slavebuilddir": "test" 22:52:09 INFO - } 22:52:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 22:52:09 INFO - Running post-action listener: _resource_record_post_action 22:52:09 INFO - [mozharness: 2017-04-19 05:52:09.691174Z] Finished read-buildbot-config step (success) 22:52:09 INFO - [mozharness: 2017-04-19 05:52:09.691311Z] Running download-and-extract step. 22:52:09 INFO - Running pre-action listener: _resource_record_pre_action 22:52:09 INFO - Running main action method: download_and_extract 22:52:09 INFO - mkdir: /builds/slave/test/build/tests 22:52:09 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')]} 22:52:09 INFO - trying https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 22:52:09 INFO - Downloading https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 22:52:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 22:52:10 INFO - Downloaded 1325 bytes. 22:52:10 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 22:52:10 INFO - Using the following test package requirements: 22:52:10 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:52:10 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 22:52:10 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:52:10 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:52:10 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 22:52:10 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:52:10 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 22:52:10 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:52:10 INFO - u'jsshell-mac.zip'], 22:52:10 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:52:10 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 22:52:10 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 22:52:10 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:52:10 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 22:52:10 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:52:10 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 22:52:10 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:52:10 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 22:52:10 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 22:52:10 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 22:52:10 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:52:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:52:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:52:10 INFO - Fetch https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:52:12 INFO - Expected file size: 52629600 22:52:12 INFO - Obtained file size: 52629600 22:52:28 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:52:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 22:52:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:52:28 INFO - Fetch https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 22:52:30 INFO - Expected file size: 52629600 22:52:30 INFO - Obtained file size: 52629600 22:52:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 22:52:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:52:45 INFO - Fetch https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 22:52:47 INFO - Expected file size: 14408802 22:52:47 INFO - Obtained file size: 14408802 22:52:47 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')]} 22:52:47 INFO - trying https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:52:47 INFO - Downloading https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 22:52:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 22:52:50 INFO - Downloaded 71497603 bytes. 22:52:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:52:50 INFO - mkdir: /builds/slave/test/properties 22:52:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:52:50 INFO - Writing to file /builds/slave/test/properties/build_url 22:52:50 INFO - Contents: 22:52:50 INFO - build_url:https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 22:52:50 INFO - Running post-action listener: _resource_record_post_action 22:52:50 INFO - Running post-action listener: set_extra_try_arguments 22:52:50 INFO - [mozharness: 2017-04-19 05:52:50.854315Z] Finished download-and-extract step (success) 22:52:50 INFO - [mozharness: 2017-04-19 05:52:50.854457Z] Running populate-webroot step. 22:52:50 INFO - Running pre-action listener: _resource_record_pre_action 22:52:50 INFO - Running main action method: populate_webroot 22:52:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:52:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:52:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:52:50 INFO - '--tppagecycles', 22:52:50 INFO - '7'], 22:52:50 INFO - 'tests': ['tsvgm']}}, 22:52:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:52:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:52:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:52:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:52:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:52:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:52:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:52:50 INFO - 'talos_options': ['--disable-e10s'], 22:52:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:52:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:52:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:52:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:52:50 INFO - 'talos_options': ['--disable-e10s'], 22:52:50 INFO - 'tests': ['damp', 'tps']}, 22:52:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:52:50 INFO - 'tests': ['damp', 'tps']}, 22:52:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:52:50 INFO - 'tests': ['dromaeo_dom']}, 22:52:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:52:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:52:50 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 22:52:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 22:52:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:52:50 INFO - 'tests': ['a11yr', 22:52:50 INFO - 'ts_paint', 22:52:50 INFO - 'tpaint', 22:52:50 INFO - 'sessionrestore', 22:52:50 INFO - 'sessionrestore_no_auto_restore', 22:52:50 INFO - 'tabpaint']}, 22:52:50 INFO - 'other-e10s': {'tests': ['a11yr', 22:52:50 INFO - 'ts_paint', 22:52:50 INFO - 'tpaint', 22:52:50 INFO - 'sessionrestore', 22:52:50 INFO - 'sessionrestore_no_auto_restore', 22:52:50 INFO - 'tabpaint']}, 22:52:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:52:50 INFO - 'ts_paint', 22:52:50 INFO - 'tpaint', 22:52:50 INFO - 'sessionrestore', 22:52:50 INFO - 'sessionrestore_no_auto_restore', 22:52:50 INFO - 'tabpaint']}, 22:52:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:52:50 INFO - 'ts_paint', 22:52:50 INFO - 'tpaint', 22:52:50 INFO - 'sessionrestore', 22:52:50 INFO - 'sessionrestore_no_auto_restore', 22:52:50 INFO - 'tabpaint']}, 22:52:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:52:50 INFO - 'tests': ['a11yr', 22:52:50 INFO - 'ts_paint', 22:52:50 INFO - 'tpaint', 22:52:50 INFO - 'sessionrestore', 22:52:50 INFO - 'sessionrestore_no_auto_restore', 22:52:50 INFO - 'tabpaint']}, 22:52:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:52:50 INFO - 'tests': ['a11yr', 22:52:50 INFO - 'ts_paint', 22:52:50 INFO - 'tpaint', 22:52:50 INFO - 'sessionrestore', 22:52:50 INFO - 'sessionrestore_no_auto_restore', 22:52:50 INFO - 'tabpaint']}, 22:52:50 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 22:52:50 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 22:52:50 INFO - 'bloom_basic_ref']}, 22:52:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:52:50 INFO - 'tests': ['tsvgx', 22:52:50 INFO - 'tsvgr_opacity', 22:52:50 INFO - 'tart', 22:52:50 INFO - 'tscrollx', 22:52:50 INFO - 'cart', 22:52:50 INFO - 'tsvg_static']}, 22:52:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:52:50 INFO - 'tsvgr_opacity', 22:52:50 INFO - 'tart', 22:52:50 INFO - 'tscrollx', 22:52:50 INFO - 'cart', 22:52:50 INFO - 'tsvg_static']}, 22:52:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:52:50 INFO - 'talos_options': ['--disable-e10s'], 22:52:50 INFO - 'tests': ['tp5o']}, 22:52:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:52:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:52:50 INFO - 'talos_options': ['--disable-e10s', 22:52:50 INFO - '--xperf_path', 22:52:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:52:50 INFO - 'tests': ['tp5n']}, 22:52:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:52:50 INFO - 'talos_options': ['--xperf_path', 22:52:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:52:50 INFO - 'tests': ['tp5n']}}, 22:52:50 INFO - 'talos.zip': {'path': '', 22:52:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:52:50 INFO - Running post-action listener: _resource_record_post_action 22:52:50 INFO - [mozharness: 2017-04-19 05:52:50.874364Z] Finished populate-webroot step (success) 22:52:50 INFO - [mozharness: 2017-04-19 05:52:50.874499Z] Running create-virtualenv step. 22:52:50 INFO - Running pre-action listener: _resource_record_pre_action 22:52:50 INFO - Running main action method: create_virtualenv 22:52:50 INFO - Creating virtualenv /builds/slave/test/build/venv 22:52:50 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 22:52:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:52:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:52:51 INFO - Using real prefix '/tools/python27' 22:52:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:52:51 INFO - Installing distribute.............................................................................................................................................................................................done. 22:52:54 INFO - Installing pip.................done. 22:52:54 INFO - Return code: 0 22:52:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:52:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:52:54 INFO - Reading from file tmpfile_stdout 22:52:54 INFO - Output received: 22:52:54 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) 22:52:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:52:54 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')]} 22:52:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:54 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')]} 22:52:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:54 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 0x103715b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe109d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f3d30>, '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 0x103419db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034e8be8>, '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.PT3NkkqzUs/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.96K9bhmIlw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 22:52:54 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 22:52:54 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 22:52:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PT3NkkqzUs/Render', 22:52:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:52:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:52:54 INFO - 'HOME': '/Users/cltbld', 22:52:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:52:54 INFO - 'LOGNAME': 'cltbld', 22:52:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:54 INFO - 'MOZ_NO_REMOTE': '1', 22:52:54 INFO - 'NO_EM_RESTART': '1', 22:52:54 INFO - 'PAGER': '/bin/cat', 22:52:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:52:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:54 INFO - 'PWD': '/builds/slave/test', 22:52:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:52:54 INFO - 'SHELL': '/bin/bash', 22:52:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96K9bhmIlw/Listeners', 22:52:54 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 22:52:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:52:54 INFO - 'USER': 'cltbld', 22:52:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:52:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:52:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:54 INFO - 'XPC_FLAGS': '0x0', 22:52:54 INFO - 'XPC_SERVICE_NAME': '0', 22:52:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:52:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:55 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 22:52:55 INFO - Cleaning up... 22:52:55 INFO - Return code: 0 22:52:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:52:55 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')]} 22:52:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:52:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:55 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')]} 22:52:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:52:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:52:55 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 0x103715b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe109d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f3d30>, '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 0x103419db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034e8be8>, '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.PT3NkkqzUs/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.96K9bhmIlw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 22:52:55 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 22:52:55 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 22:52:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PT3NkkqzUs/Render', 22:52:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:52:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:52:55 INFO - 'HOME': '/Users/cltbld', 22:52:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:52:55 INFO - 'LOGNAME': 'cltbld', 22:52:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:52:55 INFO - 'MOZ_NO_REMOTE': '1', 22:52:55 INFO - 'NO_EM_RESTART': '1', 22:52:55 INFO - 'PAGER': '/bin/cat', 22:52:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:52:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:52:55 INFO - 'PWD': '/builds/slave/test', 22:52:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:52:55 INFO - 'SHELL': '/bin/bash', 22:52:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96K9bhmIlw/Listeners', 22:52:55 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 22:52:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:52:55 INFO - 'USER': 'cltbld', 22:52:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:52:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:52:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:52:55 INFO - 'XPC_FLAGS': '0x0', 22:52:55 INFO - 'XPC_SERVICE_NAME': '0', 22:52:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:52:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:52:55 INFO - Downloading/unpacking psutil>=3.1.1 22:52:55 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 22:52:55 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 22:52:55 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 22:52:55 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 22:52:55 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 22:52:55 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 22:52:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:52:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:52:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:52:58 INFO - Installing collected packages: psutil 22:52:58 INFO - Running setup.py install for psutil 22:52:58 INFO - building 'psutil._psutil_osx' extension 22:52:58 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 22:52:59 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 22:52:59 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 22:52:59 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 22:52:59 INFO - building 'psutil._psutil_posix' extension 22:52:59 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 22:52:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:52:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:52:59 INFO - ^ 22:52:59 INFO - 1 warning generated. 22:52:59 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 22:53:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:53:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:53:00 INFO - Successfully installed psutil 22:53:00 INFO - Cleaning up... 22:53:00 INFO - Return code: 0 22:53:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:53:00 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')]} 22:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:00 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')]} 22:53:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:00 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 0x103715b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe109d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f3d30>, '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 0x103419db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034e8be8>, '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.PT3NkkqzUs/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.96K9bhmIlw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 22:53:00 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 22:53:00 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 22:53:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PT3NkkqzUs/Render', 22:53:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:00 INFO - 'HOME': '/Users/cltbld', 22:53:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:00 INFO - 'LOGNAME': 'cltbld', 22:53:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:00 INFO - 'MOZ_NO_REMOTE': '1', 22:53:00 INFO - 'NO_EM_RESTART': '1', 22:53:00 INFO - 'PAGER': '/bin/cat', 22:53:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:00 INFO - 'PWD': '/builds/slave/test', 22:53:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:00 INFO - 'SHELL': '/bin/bash', 22:53:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96K9bhmIlw/Listeners', 22:53:00 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 22:53:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:00 INFO - 'USER': 'cltbld', 22:53:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:00 INFO - 'XPC_FLAGS': '0x0', 22:53:00 INFO - 'XPC_SERVICE_NAME': '0', 22:53:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:53:00 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 22:53:00 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 22:53:00 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 22:53:00 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 22:53:00 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 22:53:00 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 22:53:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:53:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:53:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:53:03 INFO - Installing collected packages: mozsystemmonitor 22:53:03 INFO - Running setup.py install for mozsystemmonitor 22:53:03 INFO - Successfully installed mozsystemmonitor 22:53:03 INFO - Cleaning up... 22:53:03 INFO - Return code: 0 22:53:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:53:03 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')]} 22:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:03 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')]} 22:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:04 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 0x103715b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe109d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f3d30>, '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 0x103419db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034e8be8>, '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.PT3NkkqzUs/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.96K9bhmIlw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 22:53:04 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 22:53:04 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 22:53:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PT3NkkqzUs/Render', 22:53:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:04 INFO - 'HOME': '/Users/cltbld', 22:53:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:04 INFO - 'LOGNAME': 'cltbld', 22:53:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:04 INFO - 'MOZ_NO_REMOTE': '1', 22:53:04 INFO - 'NO_EM_RESTART': '1', 22:53:04 INFO - 'PAGER': '/bin/cat', 22:53:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:04 INFO - 'PWD': '/builds/slave/test', 22:53:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:04 INFO - 'SHELL': '/bin/bash', 22:53:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96K9bhmIlw/Listeners', 22:53:04 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 22:53:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:04 INFO - 'USER': 'cltbld', 22:53:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:04 INFO - 'XPC_FLAGS': '0x0', 22:53:04 INFO - 'XPC_SERVICE_NAME': '0', 22:53:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:04 INFO - Downloading/unpacking jsonschema==2.5.1 22:53:04 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 22:53:04 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 22:53:04 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 22:53:04 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 22:53:04 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 22:53:04 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 22:53:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:53:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:53:07 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 22:53:07 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 22:53:07 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 22:53:07 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 22:53:07 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 22:53:07 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 22:53:07 INFO - Downloading functools32-3.2.3-2.tar.gz 22:53:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:53:07 INFO - warning: no files found matching '*.txt' 22:53:07 INFO - no previously-included directories found matching 'build' 22:53:07 INFO - no previously-included directories found matching 'dist' 22:53:07 INFO - no previously-included directories found matching '.git*' 22:53:07 INFO - Installing collected packages: jsonschema, functools32 22:53:07 INFO - Running setup.py install for functools32 22:53:07 INFO - warning: no files found matching '*.txt' 22:53:07 INFO - no previously-included directories found matching 'build' 22:53:07 INFO - no previously-included directories found matching 'dist' 22:53:07 INFO - no previously-included directories found matching '.git*' 22:53:07 INFO - Successfully installed jsonschema functools32 22:53:07 INFO - Cleaning up... 22:53:08 INFO - Return code: 0 22:53:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:53: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')]} 22:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53: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')]} 22:53:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:08 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 0x103715b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe109d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f3d30>, '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 0x103419db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034e8be8>, '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.PT3NkkqzUs/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.96K9bhmIlw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 22:53:08 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 22:53:08 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 22:53:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PT3NkkqzUs/Render', 22:53:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:08 INFO - 'HOME': '/Users/cltbld', 22:53:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:08 INFO - 'LOGNAME': 'cltbld', 22:53:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:08 INFO - 'MOZ_NO_REMOTE': '1', 22:53:08 INFO - 'NO_EM_RESTART': '1', 22:53:08 INFO - 'PAGER': '/bin/cat', 22:53:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:08 INFO - 'PWD': '/builds/slave/test', 22:53:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:08 INFO - 'SHELL': '/bin/bash', 22:53:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96K9bhmIlw/Listeners', 22:53:08 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 22:53:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:08 INFO - 'USER': 'cltbld', 22:53:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:08 INFO - 'XPC_FLAGS': '0x0', 22:53:08 INFO - 'XPC_SERVICE_NAME': '0', 22:53:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:53:08 INFO - Cleaning up... 22:53:08 INFO - Return code: 0 22:53:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:53: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')]} 22:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53: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')]} 22:53:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:08 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 0x103715b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe109d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f3d30>, '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 0x103419db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034e8be8>, '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.PT3NkkqzUs/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.96K9bhmIlw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 22:53:08 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 22:53:08 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 22:53:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PT3NkkqzUs/Render', 22:53:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:08 INFO - 'HOME': '/Users/cltbld', 22:53:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:08 INFO - 'LOGNAME': 'cltbld', 22:53:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:08 INFO - 'MOZ_NO_REMOTE': '1', 22:53:08 INFO - 'NO_EM_RESTART': '1', 22:53:08 INFO - 'PAGER': '/bin/cat', 22:53:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:08 INFO - 'PWD': '/builds/slave/test', 22:53:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:08 INFO - 'SHELL': '/bin/bash', 22:53:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96K9bhmIlw/Listeners', 22:53:08 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 22:53:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:08 INFO - 'USER': 'cltbld', 22:53:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:08 INFO - 'XPC_FLAGS': '0x0', 22:53:08 INFO - 'XPC_SERVICE_NAME': '0', 22:53:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:08 INFO - Downloading/unpacking blobuploader==1.2.4 22:53:08 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 22:53:08 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 22:53:08 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 22:53:08 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 22:53:08 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 22:53:08 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 22:53:11 INFO - Downloading blobuploader-1.2.4.tar.gz 22:53:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:53:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:53:11 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 22:53:11 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 22:53:11 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 22:53:11 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 22:53:11 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 22:53:11 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 22:53:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:53:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:53:12 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 22:53:12 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 22:53:12 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 22:53:12 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 22:53:12 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 22:53:12 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 22:53:12 INFO - Downloading docopt-0.6.1.tar.gz 22:53:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:53:12 INFO - Installing collected packages: blobuploader, requests, docopt 22:53:12 INFO - Running setup.py install for blobuploader 22:53:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:53:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:53:12 INFO - Running setup.py install for requests 22:53:13 INFO - Running setup.py install for docopt 22:53:13 INFO - Successfully installed blobuploader requests docopt 22:53:13 INFO - Cleaning up... 22:53:13 INFO - Return code: 0 22:53:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:53:13 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')]} 22:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:13 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')]} 22:53:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:13 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 0x103715b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe109d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f3d30>, '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 0x103419db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034e8be8>, '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.PT3NkkqzUs/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.96K9bhmIlw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 22:53:13 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 22:53:13 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 22:53:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PT3NkkqzUs/Render', 22:53:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:13 INFO - 'HOME': '/Users/cltbld', 22:53:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:13 INFO - 'LOGNAME': 'cltbld', 22:53:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:13 INFO - 'MOZ_NO_REMOTE': '1', 22:53:13 INFO - 'NO_EM_RESTART': '1', 22:53:13 INFO - 'PAGER': '/bin/cat', 22:53:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:13 INFO - 'PWD': '/builds/slave/test', 22:53:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:13 INFO - 'SHELL': '/bin/bash', 22:53:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96K9bhmIlw/Listeners', 22:53:13 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 22:53:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:13 INFO - 'USER': 'cltbld', 22:53:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:13 INFO - 'XPC_FLAGS': '0x0', 22:53:13 INFO - 'XPC_SERVICE_NAME': '0', 22:53:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:53:13 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-g3Cz2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:53:13 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-nd71DA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:53:13 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-yXmtZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:53:13 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-hAFPtR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:53:14 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-5zhIBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:53:14 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-ALLdWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:53:14 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-1v2Ul5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:53:14 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-yjZaA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:53:14 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-G6AcLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:53:14 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-brNiUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:53:14 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-QtOpa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:53:14 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-jXm0k_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:53:14 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-MILki_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:53:15 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-wOK15e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:53:15 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-4FCek7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:53:15 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-oBFl6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:53:15 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-eNfmTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:53:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:53:15 INFO - Running setup.py install for manifestparser 22:53:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:53:15 INFO - Running setup.py install for mozcrash 22:53:15 INFO - Running setup.py install for mozdebug 22:53:15 INFO - Running setup.py install for mozdevice 22:53:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:53:16 INFO - Running setup.py install for mozfile 22:53:16 INFO - Running setup.py install for mozhttpd 22:53:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:53:16 INFO - Running setup.py install for mozinfo 22:53:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:53:16 INFO - Running setup.py install for mozInstall 22:53:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:53:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:53:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:53:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:53:16 INFO - Running setup.py install for mozleak 22:53:16 INFO - Running setup.py install for mozlog 22:53:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:53:16 INFO - Running setup.py install for moznetwork 22:53:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:53:17 INFO - Running setup.py install for mozprocess 22:53:17 INFO - Running setup.py install for mozprofile 22:53:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:53:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:53:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:53:17 INFO - Running setup.py install for mozrunner 22:53:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:53:17 INFO - Running setup.py install for mozscreenshot 22:53:17 INFO - Running setup.py install for moztest 22:53:17 INFO - Running setup.py install for mozversion 22:53:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:53:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:53:17 INFO - Cleaning up... 22:53:17 INFO - Return code: 0 22:53:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:53:17 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')]} 22:53:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:17 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')]} 22:53:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:17 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 0x103715b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe109d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f3d30>, '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 0x103419db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034e8be8>, '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.PT3NkkqzUs/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.96K9bhmIlw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 22:53:17 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 22:53:17 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 22:53:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PT3NkkqzUs/Render', 22:53:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:17 INFO - 'HOME': '/Users/cltbld', 22:53:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:17 INFO - 'LOGNAME': 'cltbld', 22:53:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:17 INFO - 'MOZ_NO_REMOTE': '1', 22:53:17 INFO - 'NO_EM_RESTART': '1', 22:53:17 INFO - 'PAGER': '/bin/cat', 22:53:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:17 INFO - 'PWD': '/builds/slave/test', 22:53:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:17 INFO - 'SHELL': '/bin/bash', 22:53:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96K9bhmIlw/Listeners', 22:53:17 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 22:53:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:17 INFO - 'USER': 'cltbld', 22:53:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:17 INFO - 'XPC_FLAGS': '0x0', 22:53:17 INFO - 'XPC_SERVICE_NAME': '0', 22:53:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:53:18 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-oe1vy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:53:18 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)) 22:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:53:18 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-HjLFMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:53:18 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)) 22:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:53:18 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-_YCW1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:53:18 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)) 22:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:53:18 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-gULU8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:53:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 22:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:53:18 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-IkO_41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:53:18 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)) 22:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:53:18 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-gJUDJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:53:18 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)) 22:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:53:18 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-7Zb1WH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:53:18 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)) 22:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:53:18 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-bVi6X2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:53:19 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)) 22:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:53:19 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-iIzjgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:53:19 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)) 22:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:53:19 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-V7KrXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:53:19 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)) 22:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:53:19 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-_4MU4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:53:19 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)) 22:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:53:19 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-o_HZJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 22:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:53:19 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-yGJhQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:53:19 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)) 22:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:53:19 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-0dsch3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:53:19 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)) 22:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:53:19 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-P67exj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:53:19 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)) 22:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:53:19 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-mLVwBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:53:19 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)) 22:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:53:19 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-LCcfMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:53:20 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)) 22:53:20 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)) 22:53:20 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)) 22:53:20 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)) 22:53:20 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:53:20 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:53:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:53:20 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 22:53:20 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 22:53:20 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 22:53:20 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 22:53:20 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 22:53:20 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 22:53:22 INFO - Downloading blessings-1.6.tar.gz 22:53:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:53:22 INFO - Installing collected packages: blessings 22:53:22 INFO - Running setup.py install for blessings 22:53:22 INFO - Successfully installed blessings 22:53:22 INFO - Cleaning up... 22:53:22 INFO - Return code: 0 22:53:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:53:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:53:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:53:23 INFO - Reading from file tmpfile_stdout 22:53:23 INFO - Current package versions: 22:53:23 INFO - blessings == 1.6 22:53:23 INFO - blobuploader == 1.2.4 22:53:23 INFO - docopt == 0.6.1 22:53:23 INFO - functools32 == 3.2.3-2 22:53:23 INFO - jsonschema == 2.5.1 22:53:23 INFO - manifestparser == 1.1 22:53:23 INFO - mozInstall == 1.12 22:53:23 INFO - mozcrash == 1.0 22:53:23 INFO - mozdebug == 0.1 22:53:23 INFO - mozdevice == 0.50 22:53:23 INFO - mozfile == 1.2 22:53:23 INFO - mozhttpd == 0.7 22:53:23 INFO - mozinfo == 0.9 22:53:23 INFO - mozleak == 0.1 22:53:23 INFO - mozlog == 3.4 22:53:23 INFO - moznetwork == 0.27 22:53:23 INFO - mozprocess == 0.25 22:53:23 INFO - mozprofile == 0.28 22:53:23 INFO - mozrunner == 6.13 22:53:23 INFO - mozscreenshot == 0.1 22:53:23 INFO - mozsystemmonitor == 0.3 22:53:23 INFO - moztest == 0.8 22:53:23 INFO - mozversion == 1.4 22:53:23 INFO - psutil == 3.1.1 22:53:23 INFO - requests == 1.2.3 22:53:23 INFO - wsgiref == 0.1.2 22:53:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:53:23 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')]} 22:53:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:23 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')]} 22:53:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:23 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 0x103715b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe109d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f3d30>, '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 0x103419db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034e8be8>, '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.PT3NkkqzUs/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.96K9bhmIlw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 22:53:23 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 22:53:23 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 22:53:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PT3NkkqzUs/Render', 22:53:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:23 INFO - 'HOME': '/Users/cltbld', 22:53:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:23 INFO - 'LOGNAME': 'cltbld', 22:53:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:23 INFO - 'MOZ_NO_REMOTE': '1', 22:53:23 INFO - 'NO_EM_RESTART': '1', 22:53:23 INFO - 'PAGER': '/bin/cat', 22:53:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:23 INFO - 'PWD': '/builds/slave/test', 22:53:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:23 INFO - 'SHELL': '/bin/bash', 22:53:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96K9bhmIlw/Listeners', 22:53:23 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 22:53:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:23 INFO - 'USER': 'cltbld', 22:53:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:23 INFO - 'XPC_FLAGS': '0x0', 22:53:23 INFO - 'XPC_SERVICE_NAME': '0', 22:53:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:23 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)) 22:53:23 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)) 22:53:23 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)) 22:53:23 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)) 22:53:23 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)) 22:53:23 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)) 22:53:23 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)) 22:53:23 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)) 22:53:23 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)) 22:53:23 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)) 22:53:23 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)) 22:53:23 INFO - Cleaning up... 22:53:23 INFO - Return code: 0 22:53:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:53:23 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')]} 22:53:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:23 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')]} 22:53:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:23 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 0x103715b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe109d7cba0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034f3d30>, '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 0x103419db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034e8be8>, '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.PT3NkkqzUs/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.96K9bhmIlw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 22:53:23 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 22:53:23 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 22:53:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PT3NkkqzUs/Render', 22:53:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:23 INFO - 'HOME': '/Users/cltbld', 22:53:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:23 INFO - 'LOGNAME': 'cltbld', 22:53:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:23 INFO - 'MOZ_NO_REMOTE': '1', 22:53:23 INFO - 'NO_EM_RESTART': '1', 22:53:23 INFO - 'PAGER': '/bin/cat', 22:53:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:23 INFO - 'PWD': '/builds/slave/test', 22:53:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:23 INFO - 'SHELL': '/bin/bash', 22:53:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96K9bhmIlw/Listeners', 22:53:23 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 22:53:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:23 INFO - 'USER': 'cltbld', 22:53:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:23 INFO - 'XPC_FLAGS': '0x0', 22:53:23 INFO - 'XPC_SERVICE_NAME': '0', 22:53:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:53:23 INFO - Cleaning up... 22:53:23 INFO - Return code: 0 22:53:23 INFO - Running post-action listener: _resource_record_post_action 22:53:23 INFO - Running post-action listener: _start_resource_monitoring 22:53:23 INFO - Starting resource monitoring. 22:53:23 INFO - [mozharness: 2017-04-19 05:53:23.808320Z] Finished create-virtualenv step (success) 22:53:23 INFO - [mozharness: 2017-04-19 05:53:23.809335Z] Running install step. 22:53:23 INFO - Running pre-action listener: _resource_record_pre_action 22:53:23 INFO - Running main action method: install 22:53:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:53:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:53:24 INFO - Reading from file tmpfile_stdout 22:53:24 INFO - Detecting whether we're running mozinstall >=1.0... 22:53:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:53:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:53:24 INFO - Reading from file tmpfile_stdout 22:53:24 INFO - Output received: 22:53:24 INFO - Usage: mozinstall [options] installer 22:53:24 INFO - Options: 22:53:24 INFO - -h, --help show this help message and exit 22:53:24 INFO - -d DEST, --destination=DEST 22:53:24 INFO - Directory to install application into. [default: 22:53:24 INFO - "/builds/slave/test"] 22:53:24 INFO - --app=APP Application being installed. [default: firefox] 22:53:24 INFO - mkdir: /builds/slave/test/build/application 22:53:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:53:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:53:43 INFO - Reading from file tmpfile_stdout 22:53:43 INFO - Output received: 22:53:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:53:43 INFO - Running post-action listener: _resource_record_post_action 22:53:43 INFO - [mozharness: 2017-04-19 05:53:43.848516Z] Finished install step (success) 22:53:43 INFO - [mozharness: 2017-04-19 05:53:43.848635Z] Running run-tests step. 22:53:43 INFO - Running pre-action listener: _resource_record_pre_action 22:53:43 INFO - Running main action method: run_tests 22:53:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:53:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:53:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:53:43 INFO - 2017-04-18 22:53:43.860 screenresolution[1624:7528] starting screenresolution argv=screenresolution get 22:53:43 INFO - 2017-04-18 22:53:43.863 screenresolution[1624:7528] Display 0: 1600x1200x32@60 22:53:43 INFO - 2017-04-18 22:53:43.868 screenresolution[1625:7531] starting screenresolution argv=screenresolution list 22:53:43 INFO - Available Modes on Display 0 22:53:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:53:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:53:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:53:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:53:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:53:44 INFO - Intel Iris: 22:53:44 INFO - Chipset Model: Intel Iris 22:53:44 INFO - Type: GPU 22:53:44 INFO - Bus: Built-In 22:53:44 INFO - VRAM (Dynamic, Max): 1536 MB 22:53:44 INFO - Vendor: Intel (0x8086) 22:53:44 INFO - Device ID: 0x0a2e 22:53:44 INFO - Revision ID: 0x0009 22:53:44 INFO - Displays: 22:53:44 INFO - Display: 22:53:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:53:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:53:44 INFO - Display Serial Number: 1600x1200 60 22:53:44 INFO - Main Display: Yes 22:53:44 INFO - Mirror: Off 22:53:44 INFO - Online: Yes 22:53:44 INFO - Rotation: Supported 22:53:44 INFO - Return code: 0 22:53:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:53:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:53:44 INFO - Python 2.7.3 22:53:44 INFO - Return code: 0 22:53:44 INFO - grabbing minidump binary from tooltool 22:53: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')]} 22:53:44 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 0x1034f3d30>, '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 0x103419db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1034e8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:53:44 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 22:53:44 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 22:53:44 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 22:53:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:53:44 INFO - Return code: 0 22:53:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:53:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:53:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:53:44 INFO - ENV: RUST_BACKTRACE is now 1 22:53:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:53:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:53:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--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 22:53:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0154 --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 22:53:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.PT3NkkqzUs/Render', 22:53:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:44 INFO - 'HOME': '/Users/cltbld', 22:53:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:44 INFO - 'LOGNAME': 'cltbld', 22:53:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:53:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:53:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:44 INFO - 'MOZ_NO_REMOTE': '1', 22:53:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:53:44 INFO - 'NO_EM_RESTART': '1', 22:53:44 INFO - 'PAGER': '/bin/cat', 22:53:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:44 INFO - 'PWD': '/builds/slave/test', 22:53:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:53:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:44 INFO - 'RUST_BACKTRACE': '1', 22:53:44 INFO - 'SHELL': '/bin/bash', 22:53:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.96K9bhmIlw/Listeners', 22:53:44 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 22:53:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:44 INFO - 'USER': 'cltbld', 22:53:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:44 INFO - 'XPC_FLAGS': '0x0', 22:53:44 INFO - 'XPC_SERVICE_NAME': '0', 22:53:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--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 22:53:44 INFO - mozversion application_buildid: 20170418162120 22:53:44 INFO - mozversion application_changeset: e6f714bb5eb2b5d6aff241ae1c46044c3563de24 22:53:44 INFO - mozversion application_display_name: Nightly 22:53:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:53:44 INFO - mozversion application_name: Firefox 22:53:44 INFO - mozversion application_remotingname: firefox 22:53:44 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 22:53:44 INFO - mozversion application_vendor: Mozilla 22:53:44 INFO - mozversion application_version: 55.0a1 22:53:44 INFO - mozversion platform_buildid: 20170418162120 22:53:44 INFO - mozversion platform_changeset: e6f714bb5eb2b5d6aff241ae1c46044c3563de24 22:53:44 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 22:53:44 INFO - mozversion platform_version: 55.0a1 22:53:44 INFO - using testdate: 1492581224 22:53:44 INFO - actual date: 1492581224 22:53:44 INFO - starting webserver on 'localhost:49241' 22:53:44 INFO - SUITE-START | Running 2 tests 22:53:44 INFO - TEST-START | dromaeo_css 22:53:44 INFO - operating with platform_type : mac_ 22:53:44 INFO - Initialising browser for dromaeo_css test... 22:53:44 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmp7v7inc/profile http://localhost:49241/getInfo.html) 22:53:47 INFO - PID 1635 | __metrics Screen width/height:1600/1200 22:53:47 INFO - PID 1635 | colorDepth:24 22:53:47 INFO - PID 1635 | Browser inner width/height: 1024/689 22:53:47 INFO - PID 1635 | __metrics 22:53:47 INFO - PID 1635 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:53:47 INFO - PID 1635 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:53:48 INFO - TEST-INFO | 1635: exit 0 22:53:48 INFO - Browser initialized. 22:53:48 INFO - Running cycle 1/1 for dromaeo_css test... 22:53:48 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmp7v7inc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:54:00 INFO - PID 1637 | RSS: Main: 242941952 22:54:00 INFO - PID 1637 | 22:57:06 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 22:57:08 INFO - PID 1637 | RSS: Main: 464486400 22:57:08 INFO - PID 1637 | 22:58:50 INFO - PID 1637 | ************************* 22:58:50 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 22:58:50 INFO - PID 1637 | 22:58:50 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:58:50 INFO - PID 1637 | Full stack: 22:58:50 INFO - PID 1637 | ************************* 22:58:50 INFO - PID 1637 | ************************* 22:58:50 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 22:58:50 INFO - PID 1637 | 22:58:50 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:58:50 INFO - PID 1637 | Full stack: 22:58:50 INFO - PID 1637 | ************************* 22:58:50 INFO - PID 1637 | ************************* 22:58:50 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 22:58:50 INFO - PID 1637 | 22:58:50 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:58:50 INFO - PID 1637 | Full stack: 22:58:50 INFO - PID 1637 | ************************* 22:58:50 INFO - PID 1637 | ************************* 22:58:50 INFO - PID 1637 | A coding exception was thrown and uncaught in a Task. 22:58:50 INFO - PID 1637 | 22:58:50 ERROR - PID 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:58:50 INFO - PID 1637 | Full stack: 22:58:50 INFO - PID 1637 | ************************* 23:00:11 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 23:00:12 INFO - PID 1637 | RSS: Main: 368623616 23:00:12 INFO - PID 1637 | 23:03:14 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 23:03:15 INFO - PID 1637 | RSS: Main: 359510016 23:03:15 INFO - PID 1637 | 23:06:17 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 23:06:18 INFO - PID 1637 | RSS: Main: 360071168 23:06:18 INFO - PID 1637 | 23:09:21 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 23:09:22 INFO - PID 1637 | RSS: Main: 340922368 23:09:22 INFO - PID 1637 | 23:12:24 INFO - PID 1637 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 23:12:25 INFO - PID 1637 | RSS: Main: 377671680 23:12:25 INFO - PID 1637 | 23:12:25 INFO - PID 1637 | __start_tp_report 23:12:25 INFO - PID 1637 | _x_x_mozilla_page_load 23:12:25 INFO - PID 1637 | _x_x_mozilla_page_load_details 23:12:25 INFO - PID 1637 | |i|pagename|runs| 23:12:25 INFO - PID 1637 | |0;dojo.html;3505.6143824179344;3528.7529872908876;3532.7527073104875;3555.2533967866757;3565.9643403565956;6058.4850287725485;6181.320054793969;6263.185785847845;6282.68586570672;6302.369763023701;6167.845803854906;6173.598716083451;6224.502039836802;6228.439440450358;6247.12540244365;5242;5429.4570542945785;5436.3476382834115;5439.401665816757;5489;5520.558355331564;5532.6680399175975;5550.91673624896;5570.053090974544;5615.989121958046;5309.150535914274;5414.972925135349;5424.213489044067;5427.402985671573;5464.480874316933;5459.290292262021;5495.093309603911;5500.119980803052;5501.422350653163;5572.219889215513;5384.838454846361;5413.729313534308;5440.9934162180125;5467.043267428224;5484.095124304525;6071;6141.24053858259;6143.972489078594;6146.231721034871;6247.187865577492;6662.133922590032;6665.566738162004;6676.065350850885;6678.632675202862;6680.23177334603;6756;6756.2568117507035;6774.5257831951785;6776.593404395752;6776.9322306777285;6145.047517634774;6242.344553821783;6258.061290806416;6323.36766323364;6333.018382150774;6570.868582628321;6768.9661551691925;6843.828904277333;6889.276625954303;6916;6427.775027874002;6437.324080971431;6477.676116194171;6555.934440655532;6578.210614726264;6670.299618540081;6735.360140786617;6753.425958793459;6754.358335958076;6768.458523318122;6631.30371311015;6646.8005959821285;6650.567713098633;6653.2681405045405;6655.334466553306;5378.91931621026;5382.838514844561;6371.23545174582;6392.776252831129;6460.515461340418;5279.102552566073;5289.841304760864;5291.285676433709;5291.973540132351;5301.814436494704;4661.394018777605;4678.3216433616935;4771.045790841846;4776.808927642855;4778.641601879873;6125.142480052797;6168.321484636686;6170;6182.1345011698395;6184.783532576338;5609.691466969274;6524.543281970311;6645.302243264484;6743.831404214836;6744.393004629509;6232.376762323732;6263.96868015657;6286;6287.088372186099;6292.244930608264;5857.6192547485125;5875.059990401601;5894.734736936848;5895.086261629454;5946.524278057745;6558.672066396756;6637;6653.900191497035;6809.977108068793;6870.003849441903;5840.2991641003355;6551.050097735897;6650.102236198051;6749.493787965824;6791.830204244933;6718.630475323904;6862.622555759481;6864.691088900911;6878.55289406187;6879.3120687930805;6374.1394911688185;6604.7027883746305;6642.269350371552;6644.734210631714;6647.60114393138;5361.571074313967;6535.411812936668;6543;6546.672666366758;6546.738130474918;6362.586431881913;6380.776672816429;6386.457151142019;6386.840328991812;6426.196725409324;6477.51418643613;6499.415052645285;6505.967470162619;6507.479401647952;6530.020496925499;6234.532410069353;6412.487001039999;6414.743410263537;6424.421802037839;6430.260520040255;6319.936800632118;6508.73965041412;6536.934630653823;6540.313267106885;6548.377904099103;5362.7586758596735;6309.4952403806665;6329.208848893888;6333.2716737575765;6362.427381535684 23:12:25 INFO - PID 1637 | |1;ext.html;782.2451334462243;800.9399295052871;1109.522905150785;1132.790433769752;1135.4152611405134;28465.715342846564;36597;36896.63103368966;36930.07674808128;36932.81533592335;38454.61545384545;38790.41814372787;38830.61169388305;38830.64092756754;38881.80559097208;6059.969700151493;6218.347073557279;8478.364122690808;8630.611622476972;8633.69782057628;8367.58162091896;8524.701635442761;8532.61603227853;8564.700235491759;8591.312694984386;8552;8615.870761938577;8622.827543449128;8630.352723545728;8654.826903461926;8504.702335418293;8522.914770852305;8580.570971451403;8584.656613735444;8589.28214357131;8391.244787969112;8461.492310461392;8462.196091371372;8466.195711407407;8469.872951905727;36484.45273320902;36651.08372290687;36807.63192368069;36812.44781328282;36832.895013149646;3546.4503002034467;3577.266660334625;3581.5702115746276;3585.1574879903465;3585.2470981094;24166.637500437508;30306.393872122655;30322.54516182259;30412.239694007825;30437.391252175054;3098.98450507746;3127.6246850377875;3147.6065491813524;3158.463061279565;3164.841757912095;271534;278227.6088619564;278661;278670;279439;32329;32372.514412283836;38533.03667408281;47495.287570685774;47789.80525486821;39796.80609581717;41942.370864437056;47818.28272575914;48614;49336.50663493319;13458.259795711134;13565.050446468653;13567.1859688419;13591.252481113435;13596.252206128758;9435.6697515588;9585;11017.008469237648;11049.668509944715;11075.72310692239;11564.074874010059;11627.709307267385;11754.942055215071;11760.647180584425;11800.763984720257;4686.250199967989;4992.976439829826;5037.773300201499;5366.6243362965;5459.945400545944;996.4220751963844;1035.316690983947;1044.4099084017505;1053.1832588784287;1075.9677209683723;18375.632487350445;22617.869106544607;23775.286741397787;23822.404439889142;23887.880560597085;1012.7569383347943;1116.748731535398;1151.6372342712018;1153.1985270237271;1154.3477934966718;10394.856565777758;10700.839487407695;12126.454309556091;12171;12229.571964981184;278300;278656;278680;279205;279678;51259.23111153336;51314;51320.74339628278;51386.48613513892;51418.74290628598;1332.2938842413537;1477.6232060824636;1510.4864346122151;1514.4547962733627;1515.4695856450376;21905.452363691034;23036.193733219257;23237.76762232356;23240.4189895254;23262.418439539146;31121.221969450944;35279.94160175199;36454.8177259112;36546.451803222444;36555.81722091373;260541;261070.69464652555;262765;262950.6852465802;263060;28588.857055714587;29040.419191615627;32699.509507356932;33406.49890251697;33500.49749253812;42223.366649499665;43068.78465607652;43136.35295470633;43159.568404315556;43490;28159.859200703864;28939.42121157607;29406.970756023435;30133.69866301309;30197;24959.87520062388;29597.556036659036;29765;29993.850030749705;30090.24774380658;35503.28993420169;35508.46737298891;35529.28941421105;35667.57329706783;35694;23273.650895236922;28093.719062809927;28993.275168120123;29195.124146275644;29767.702322976493;21475.677864831727;21476.556789306673;23227.419314517272;23466.826658667338;23564.410889727893 23:12:25 INFO - PID 1637 | |2;jquery.html;421.8207261913684;425.1412147462128;426.413681188366;433.8047878454695;447.2039769210806;19376.806231937677;21038.684419733698;21971.560568788656;22047.66928496074;22058.897055147238;21378.57242855146;22143.335699928986;22554.88722556389;22625.094996200172;22914.770852291473;6076.422739839719;6372.1397611322345;6372.330905254951;6378.744850205986;6385.074164246182;5916.585838991272;6224.190855188842;6336.144620476222;6339.112524246586;6356.046593011062;5982.102684597324;6280.874382512347;6294.150289710899;6304.621722696653;6306.463950564207;6210.596311239778;6321.589096708699;6324.241091069055;6325.462335701425;6328.873422531569;5535.5715427657715;5589.860253493654;5617.269754931833;5625.09998400254;5657.12314591239;27124.59313110305;27209.72790272092;27777.861110694317;27854.442911141665;28146.437071258664;2215.612267853119;2286.039863257436;2287.4738810073777;2290.335802617239;2292.988535057331;17602.647947040987;17682;17868;18226.544336391562;18437.63124737498;2126.6384714598557;2133.765285818559;2174.913003479859;2192.7478339990785;2197.439652888519;154757;157980.21009894993;160011;161268.1936590321;162936.18531907382;10397;10908.727281817859;11077.556897724162;11765.352905590104;11958.40207989597;16355.345786168658;16356.918215408847;16373;16375.508734737978;16387.508374748777;8096.8380632387025;8096.987876515435;8402.243798058082;8575.228229459379;8577.185167409087;7176.9952206691105;7778.416618753616;7826.491278066907;7842.254985776342;8027.638756255866;6745.494087943423;7359.852802943911;7492.325690687864;7502.4748267621835;7527.435442341846;1965.496395257629;2030.6902048178843;2167.913283468675;2174.3476956913178;2216.1246307708366;915.4415806358116;952.538019060755;982.0866594067586;994.2741798487;997.3018886779283;16578;19210.51973700641;20394.69407958882;20630.38108856707;21066.946652667306;999.1008092716613;1009.268280496634;1017.699778565325;1021.2340744441668;1037.5123691864812;10291.639792607222;10701.625443109453;10709.518071686793;10720.46397680113;10907.854675259143;550549;553298;558097;573538;573673;102533.48733256286;106996.79009629723;125973;126720.46559068767;126946;1145.5188820695328;1152.095604950127;1192.7793358228755;1193.6299747078433;1194.599809063956;16753.581160471087;17539;17628.29486820564;17904.91047544754;17972.820271797114;53927;54283;55363.72318138384;56337;56629.43370566241;76873.61563192148;78104;78790.60604696939;81553;82306.3538729234;7830.960845195737;8713.43362681449;8981.1467910551;9018;9069.77325566866;11246.887531124585;11927.880721192678;11984.801519847946;13452.932735336652;14631;11478.081753459535;11710.414479276173;12017.699557510783;12019.579314723942;12086.697832554257;10876.564937402416;11089.445527723421;11266.94366528162;11320.547178112784;11696.941515292368;11927.582534611247;11943.642979664195;12049.939750301193;12164.2701437914;12220.877791221974;8646.221840034426;8655.82688346217;8775.912240877766;9094.272458203195;9094.58993366745;9618.951905240428;11844.170908036354;11978.520859165536;12069.583573974754;12165.513379464723 23:12:25 INFO - PID 1637 | |3;mootools.html;1168.5442677355827;1306.2032160382166;1373.182956141096;1439.3379045639006;1465.5237047959408;9741.805163896726;10231.48842557873;11377.715557111076;11735.178537502377;11899.881001189986;11568.652940411781;11751.588694395698;11770.705732356695;11773.470193841276;11774.470148843302;2390.509945461185;2427.672264244322;2433.2578563538113;2437.683101196842;2445.0953147335513;2016.5765189310189;2102.0435701755664;2102.453362125843;2107.7049213110176;2400.435897564071;2074.543600407908;2342.0865862313713;2418.588839897213;2439.5364880672614;2441.4994926040117;2023.5952809438097;2391.796697280733;2393.4734358441115;2393.940151496209;2406.205952035824;2334.0663734506165;2346.0146738369926;2382.29722231942;2394.0304176808245;2399.400149962509;11588.420578971018;11670.35813030283;11723.003544698711;11726.061915046776;11726.35505047222;5406.864828379322;6731.764388246388;6750.561213521106;6758.594484330956;6767.4924380671155;3858.749181303206;4064.979675101635;4250.91498170038;4251.489821221473;4254.808533615995;1097.7914196302677;1247.8377810884526;1249.993750031253;1296.3259105265208;1301.5119330251157;16612.00327980325;16644.58388540284;16665.416710415077;16667;16668.83331166697;5039.546440820343;5566.415526369754;5848.093545500455;5962.945197265814;5984.670843103584;13764.036517443683;13767.38046787897;13773.586792396243;13793;13836.86163138376;6701.530892837454;6712.597244165366;6713.832154196184;6730.629815360202;6739.123913891162;6078.848028799227;6131.233595800525;6149.41580549847;6194.659293738798;6256.968715156395;3775.4714339992424;4059.2490389278078;4500.310520432245;4575.816967321336;4638.350630911675;1295.838020247469;1308.8625694302152;1416.5042235217797;1453.2588379926103;1453.4767483705857;716.7133146741241;791.0941971442666;793.4683761879554;810.0643756461296;812.5977641067709;6629.867402652017;6917.861642767117;6920.930790692129;7027.262137475492;7064.293570642997;909.4725059465494;937.8124375125002;939.7603611079131;943.5188054092463;953.9761505962267;3726.2733766915735;4159.5008598967715;4356.912861742748;4366.847160349373;4480.708753930984;14667.19330436815;15338.923305383401;15353.385864565293;15360.231988400536;15380.230988450532;11648.301101933912;15234.085954842745;15237.619059523378;15243.314050867737;15264.771028434583;8485;8714.52070136136;8719.215270625547;8735.475871447734;8768.991565969867;6989.860202796017;7001;7166.247544007804;7169.713211471482;7222.09723784527;13115.606531804062;13572.457101716216;13644;13656;13734.175949443066;13435.044290256128;13798.241096739775;13802.65493362674;13903.096298740955;14112.576622701336;9407.247420206504;9722.27082968766;9739.610415583298;9739.659111931049;9743.951280243553;10664.466776661291;10750.408727520062;10863.67408977732;10874;10911.963363480456;7319.414446844133;9262;9524.523773811143;9802.313838031268;9858.753531161778;8959.1936725695;9001.729948101567;9355.532223388667;9356.906430935876;9779.85330220033;6883.621400823003;8168.918310816815;8337.082920878578;8338.708145214889;8362.540060296742;6021;6108.602940808833;6207.782727604512;6277.121203031488;6355.396237357444;5975.37258587842;6076.058210977306;6259.937400625936;6293.213348331458;6296.779612713533 23:12:25 INFO - PID 1637 | |4;prototype.html;383.48038407957216;401.5181781861766;410.0568692008381;410.5607000509454;410.833612386983;5907.970460147699;5930.762769489226;5931.377205393436;5968.612040217388;5999;5154.60825577094;5648.519875810561;5811.447912448321;5826;5856.677882716449;4299.957000430002;4300.623800383878;4310.676699247553;4316.84891028814;4324.97837510812;3561.9109522261892;4323.805428755698;4327.2643650579475;4344.652427805768;4360;4147.4815648044;4275.337322714984;4299.742015479082;4302.526722060571;4322.595156574111;3506.7895926244505;4245.87262382129;4258.936115958279;4265.850695225668;4266.658667306608;3459.2735525539665;3522.7710198836994;3715.665590096877;3765.9623403765886;3772.7170462215445;5422.322209723785;5686.981121701819;5704.572157088235;5748.5975981681295;5765.250517432707;3995.6603688686496;4237.665135482313;5027.798888044474;5043.672161309503;5082.974585127087;5387.4612538746205;6110.602810817282;6116.541259405518;6128.846778830565;6149.5080393568405;3716.535433070866;4051.574584668597;4152.647025002879;4171.478565179353;4217.36739489076;6137.6624285664275;6275;6303.432691057781;6335.68321583919;6439.751602443993;3695.593484716679;3894.5521265054313;4135.29699951009;4137.034908418477;4165.687573432005;4161.479815023122;4352.260115780325;4360.302351623725;4365.934510982338;4389.4513185851765;3832.6742226911024;4269.018125831014;5048.646594738333;5069.366329208849;5092.6435149539175;5393.352797664305;6091.02543593023;6138.785142519991;6155.907661385083;6239.96880015597;1454.3092031285266;1476.2028504607392;1476.8301645310926;1492.731308364483;1493.7983372244826;915.0854528327299;978.8238117138841;981.5681100315838;987.629638885418;989.6189966862665;5776.631581051749;5795.652260864362;5799.984008315652;5826.70866456684;5840.240768700041;5653.830385088454;5982.341942386334;6252.499800015988;6317.368263173737;6358.1734374531015;4754.833580824655;4989.201727723546;5020.472850350734;5055.772490237875;5169.948300517022;3283.4910588858766;3322.850471728778;3333.000099969999;3343.38147442719;3346.849391777376;5868.3251426086545;6178;6506.154199953976;6520.543561950618;6531.444827189647;5995.760169593254;6458.838529036811;6477;6484.837879053061;6508.695413238196;6533;6569.4744420445295;6570.897312859935;6571.441427478718;6596.86806263872;5900.468957793648;6313.715882785194;6390.7443702251385;6396.264429590657;6400.903986440114;5501.229827824188;5748.683822389641;6305.905411418924;6320.652364119834;6323.810285691437;4945.77744001528;6086.695665216633;6401;6447.065175549613;6584.901226481511;3561.8041007743786;4219.789010549522;4249.447571815768;4265.944855346424;4270.743755374563;5610.551155907436;6144.262688477412;6343.238811342669;6422.2614399344675;6429.421352078335;4858.95141048585;6113.938860611337;6181.629102253879;6197.783077592263;6205.906911396242;5103.744812759422;5880.14737863016;6169.020981466839;6181.938180618316;6194.225721784777;4923.793670550738;6065.241080086726;6072;6147.231596050494;6177.536684748572;5429.891402172014;5723.713814309352;6222.471089957405;6259.78090766821;6267.435930766253;5766.3368712598585;5939.821805345847;6038.969805150946;6039.698015099315;6114.510839132948 23:12:25 INFO - PID 1637 | |5;yui.html;700.3486757315694;846.7417437681506;860.8321377331423;864.0797550608597;867.6529388244701;13099.279539625317;17765.822341776577;17866.28534858604;18371.265149394043;18371.81628183718;17250.82749172508;17456.912715436403;17639.382621608245;17850.196741146647;17877.28490860364;946.9147776700113;959.7600599850037;960.13108137136;960.7309953213077;961.1301771896445;659.6240143118424;673.0308355967416;954.613381580461;963.4652767713916;986.4968865878418;1007.9344842585208;1036.1607098250404;1036.6630844975375;1036.7511797168663;1038.584566173533;1010.5806090472445;1027.337367398024;1027.9743006424862;1029.0532709906904;1030.567161792049;750.7785203365021;758.1243663568532;761.7828918758182;762.8092976755811;764.533634482965;16304.347826086941;16455.67088658232;16792;16871.578210544834;17135.971841689538;16304;16461;16777.664446710998;16870.240839162267;16875.83124168755;13533.052686311961;13732.65668358289;13752.65618359539;13918.443262269497;13949.860501394958;3392.423288041039;3410.5225268462436;3419.829008549563;3419.880304189366;3420.3672320620763;117757;118260.22609660862;120450.39774801157;120466;120983;10290.948545257226;11063.502142403611;11978.940105299418;12195.817062744067;12200.816987745191;18951.24195032211;24361.63457548138;24530.264092076908;24588.87705561461;24605.753942460706;6714;7915;7924;7950.6422211000645;7954.721584744622;3771.245750849841;3865.207632435344;3959.8416063357145;3968.206358728266;3976.681865450757;3826.4834247376803;3939.330313846653;4176;4381.2332841753205;4399.010222699867;865.16079402979;887.6405055952349;901.193431878465;901.2204443156622;903.9107875010596;649.9025146228104;652.807421810567;657.3984803904469;668.9331066893271;695.7321431248904;10766.084882784895;11311.490982905789;11555.191136620524;11868.643940681794;12075.215111017756;644.7807745366598;659.4295934017041;666.3070406776898;674.4806498995746;694.6110178300169;5311.814086506954;5325.174597937326;5462.453754624586;5482.0406428875585;5561.054620714488;79205.60397198092;108364.45817770861;108764;109865.45067274613;110041;88532;90537;92346;92705;93663.53168234116;83948;84179.5791021041;84582.73125903116;85568;86360.56819715862;10246.957981178635;10489.947550262199;11025.228234023862;11047.613333533287;11183.608573699881;42694.78652606717;42768;43020.56979430166;43215;43322.133557328045;56738.14892776694;56773.148402774816;57140;57179.426925789274;57577.71211143918;12971.41628626733;16866.74699879527;16872.409465668643;16902;16913.74629380585;17441.651166976844;17543.035133067802;18379.26482940721;18478.445646630622;18693.626127477648;16155.27301271422;16470.09414482167;16480;16839.24223409974;16945.406910758065;16463.34146634121;16565.25456354491;16843.1578421076;17014.57463563421;17071.658566828344;16041.759373609639;16159.192040398168;16175.67648647044;16179.110148941922;16350.100744459169;11561;11603.825942611036;12152.270863748203;12306.384680765746;12306.75386492283;11555.364454955059;12458.813117803422;12461.1900226485;12514.12401131912;12600.495980160691 23:12:25 INFO - PID 1637 | __end_tp_report 23:12:25 INFO - PID 1637 | __start_cc_report 23:12:25 INFO - PID 1637 | _x_x_mozilla_cycle_collect,2362 23:12:25 INFO - PID 1637 | __end_cc_report 23:12:25 INFO - PID 1637 | __startTimestamp1492582345056__endTimestamp 23:12:25 INFO - PID 1637 | 23:12:25 INFO - PID 1637 | ------- Summary: start ------- 23:12:25 INFO - PID 1637 | Number of tests: 6 23:12:25 INFO - PID 1637 | 23:12:25 INFO - PID 1637 | [#0] dojo.html Cycles:165 Average:6105.29 Median:6323.37 stddev:707.06 (11.2%) stddev-sans-first:679.18 23:12:25 INFO - PID 1637 | Values: 3505.6 3528.8 3532.8 3555.3 3566.0 6058.5 6181.3 6263.2 6282.7 6302.4 6167.8 6173.6 6224.5 6228.4 6247.1 5242.0 5429.5 5436.3 5439.4 5489.0 5520.6 5532.7 5550.9 5570.1 5616.0 5309.2 5415.0 5424.2 5427.4 5464.5 5459.3 5495.1 5500.1 5501.4 5572.2 5384.8 5413.7 5441.0 5467.0 5484.1 6071.0 6141.2 6144.0 6146.2 6247.2 6662.1 6665.6 6676.1 6678.6 6680.2 6756.0 6756.3 6774.5 6776.6 6776.9 6145.0 6242.3 6258.1 6323.4 6333.0 6570.9 6769.0 6843.8 6889.3 6916.0 6427.8 6437.3 6477.7 6555.9 6578.2 6670.3 6735.4 6753.4 6754.4 6768.5 6631.3 6646.8 6650.6 6653.3 6655.3 5378.9 5382.8 6371.2 6392.8 6460.5 5279.1 5289.8 5291.3 5292.0 5301.8 4661.4 4678.3 4771.0 4776.8 4778.6 6125.1 6168.3 6170.0 6182.1 6184.8 5609.7 6524.5 6645.3 6743.8 6744.4 6232.4 6264.0 6286.0 6287.1 6292.2 5857.6 5875.1 5894.7 5895.1 5946.5 6558.7 6637.0 6653.9 6810.0 6870.0 5840.3 6551.1 6650.1 6749.5 6791.8 6718.6 6862.6 6864.7 6878.6 6879.3 6374.1 6604.7 6642.3 6644.7 6647.6 5361.6 6535.4 6543.0 6546.7 6546.7 6362.6 6380.8 6386.5 6386.8 6426.2 6477.5 6499.4 6506.0 6507.5 6530.0 6234.5 6412.5 6414.7 6424.4 6430.3 6319.9 6508.7 6536.9 6540.3 6548.4 5362.8 6309.5 6329.2 6333.3 6362.4 23:12:25 INFO - PID 1637 | 23:12:25 INFO - PID 1637 | [#1] ext.html Cycles:180 Average:41650.71 Median:3137.62 stddev:71414.47 (2276.1%) stddev-sans-first:71548.88 23:12:25 INFO - PID 1637 | Values: 782.2 800.9 1109.5 1132.8 1135.4 28465.7 36597.0 36896.6 36930.1 36932.8 38454.6 38790.4 38830.6 38830.6 38881.8 6060.0 6218.3 8478.4 8630.6 8633.7 8367.6 8524.7 8532.6 8564.7 8591.3 8552.0 8615.9 8622.8 8630.4 8654.8 8504.7 8522.9 8580.6 8584.7 8589.3 8391.2 8461.5 8462.2 8466.2 8469.9 36484.5 36651.1 36807.6 36812.4 36832.9 3546.5 3577.3 3581.6 3585.2 3585.2 24166.6 30306.4 30322.5 30412.2 30437.4 3099.0 3127.6 3147.6 3158.5 3164.8 271534.0 278227.6 278661.0 278670.0 279439.0 32329.0 32372.5 38533.0 47495.3 47789.8 39796.8 41942.4 47818.3 48614.0 49336.5 13458.3 13565.1 13567.2 13591.3 13596.3 9435.7 9585.0 11017.0 11049.7 11075.7 11564.1 11627.7 11754.9 11760.6 11800.8 4686.3 4993.0 5037.8 5366.6 5459.9 996.4 1035.3 1044.4 1053.2 1076.0 18375.6 22617.9 23775.3 23822.4 23887.9 1012.8 1116.7 1151.6 1153.2 1154.3 10394.9 10700.8 12126.5 12171.0 12229.6 278300.0 278656.0 278680.0 279205.0 279678.0 51259.2 51314.0 51320.7 51386.5 51418.7 1332.3 1477.6 1510.5 1514.5 1515.5 21905.5 23036.2 23237.8 23240.4 23262.4 31121.2 35279.9 36454.8 36546.5 36555.8 260541.0 261070.7 262765.0 262950.7 263060.0 28588.9 29040.4 32699.5 33406.5 33500.5 42223.4 43068.8 43136.4 43159.6 43490.0 28159.9 28939.4 29407.0 30133.7 30197.0 24959.9 29597.6 29765.0 29993.9 30090.2 35503.3 35508.5 35529.3 35667.6 35694.0 23273.7 28093.7 28993.3 29195.1 29767.7 21475.7 21476.6 23227.4 23466.8 23564.4 23:12:25 INFO - PID 1637 | 23:12:25 INFO - PID 1637 | [#2] jquery.html Cycles:180 Average:35546.23 Median:12385.51 stddev:95068.75 (767.6%) stddev-sans-first:95298.86 23:12:25 INFO - PID 1637 | Values: 421.8 425.1 426.4 433.8 447.2 19376.8 21038.7 21971.6 22047.7 22058.9 21378.6 22143.3 22554.9 22625.1 22914.8 6076.4 6372.1 6372.3 6378.7 6385.1 5916.6 6224.2 6336.1 6339.1 6356.0 5982.1 6280.9 6294.2 6304.6 6306.5 6210.6 6321.6 6324.2 6325.5 6328.9 5535.6 5589.9 5617.3 5625.1 5657.1 27124.6 27209.7 27777.9 27854.4 28146.4 2215.6 2286.0 2287.5 2290.3 2293.0 17602.6 17682.0 17868.0 18226.5 18437.6 2126.6 2133.8 2174.9 2192.7 2197.4 154757.0 157980.2 160011.0 161268.2 162936.2 10397.0 10908.7 11077.6 11765.4 11958.4 16355.3 16356.9 16373.0 16375.5 16387.5 8096.8 8097.0 8402.2 8575.2 8577.2 7177.0 7778.4 7826.5 7842.3 8027.6 6745.5 7359.9 7492.3 7502.5 7527.4 1965.5 2030.7 2167.9 2174.3 2216.1 915.4 952.5 982.1 994.3 997.3 16578.0 19210.5 20394.7 20630.4 21066.9 999.1 1009.3 1017.7 1021.2 1037.5 10291.6 10701.6 10709.5 10720.5 10907.9 550549.0 553298.0 558097.0 573538.0 573673.0 102533.5 106996.8 125973.0 126720.5 126946.0 1145.5 1152.1 1192.8 1193.6 1194.6 16753.6 17539.0 17628.3 17904.9 17972.8 53927.0 54283.0 55363.7 56337.0 56629.4 76873.6 78104.0 78790.6 81553.0 82306.4 7831.0 8713.4 8981.1 9018.0 9069.8 11246.9 11927.9 11984.8 13452.9 14631.0 11478.1 11710.4 12017.7 12019.6 12086.7 10876.6 11089.4 11266.9 11320.5 11696.9 11927.6 11943.6 12049.9 12164.3 12220.9 8646.2 8655.8 8775.9 9094.3 9094.6 9619.0 11844.2 11978.5 12069.6 12165.5 23:12:25 INFO - PID 1637 | 23:12:25 INFO - PID 1637 | [#3] mootools.html Cycles:180 Average:7194.22 Median:3817.11 stddev:4610.22 (120.8%) stddev-sans-first:4600.91 23:12:25 INFO - PID 1637 | Values: 1168.5 1306.2 1373.2 1439.3 1465.5 9741.8 10231.5 11377.7 11735.2 11899.9 11568.7 11751.6 11770.7 11773.5 11774.5 2390.5 2427.7 2433.3 2437.7 2445.1 2016.6 2102.0 2102.5 2107.7 2400.4 2074.5 2342.1 2418.6 2439.5 2441.5 2023.6 2391.8 2393.5 2393.9 2406.2 2334.1 2346.0 2382.3 2394.0 2399.4 11588.4 11670.4 11723.0 11726.1 11726.4 5406.9 6731.8 6750.6 6758.6 6767.5 3858.7 4065.0 4250.9 4251.5 4254.8 1097.8 1247.8 1250.0 1296.3 1301.5 16612.0 16644.6 16665.4 16667.0 16668.8 5039.5 5566.4 5848.1 5962.9 5984.7 13764.0 13767.4 13773.6 13793.0 13836.9 6701.5 6712.6 6713.8 6730.6 6739.1 6078.8 6131.2 6149.4 6194.7 6257.0 3775.5 4059.2 4500.3 4575.8 4638.4 1295.8 1308.9 1416.5 1453.3 1453.5 716.7 791.1 793.5 810.1 812.6 6629.9 6917.9 6920.9 7027.3 7064.3 909.5 937.8 939.8 943.5 954.0 3726.3 4159.5 4356.9 4366.8 4480.7 14667.2 15338.9 15353.4 15360.2 15380.2 11648.3 15234.1 15237.6 15243.3 15264.8 8485.0 8714.5 8719.2 8735.5 8769.0 6989.9 7001.0 7166.2 7169.7 7222.1 13115.6 13572.5 13644.0 13656.0 13734.2 13435.0 13798.2 13802.7 13903.1 14112.6 9407.2 9722.3 9739.6 9739.7 9744.0 10664.5 10750.4 10863.7 10874.0 10912.0 7319.4 9262.0 9524.5 9802.3 9858.8 8959.2 9001.7 9355.5 9356.9 9779.9 6883.6 8168.9 8337.1 8338.7 8362.5 6021.0 6108.6 6207.8 6277.1 6355.4 5975.4 6076.1 6259.9 6293.2 6296.8 23:12:25 INFO - PID 1637 | 23:12:25 INFO - PID 1637 | [#4] prototype.html Cycles:180 Average:4959.73 Median:5736.16 stddev:1549.84 (27.0%) stddev-sans-first:1515.65 23:12:25 INFO - PID 1637 | Values: 383.5 401.5 410.1 410.6 410.8 5908.0 5930.8 5931.4 5968.6 5999.0 5154.6 5648.5 5811.4 5826.0 5856.7 4300.0 4300.6 4310.7 4316.8 4325.0 3561.9 4323.8 4327.3 4344.7 4360.0 4147.5 4275.3 4299.7 4302.5 4322.6 3506.8 4245.9 4258.9 4265.9 4266.7 3459.3 3522.8 3715.7 3766.0 3772.7 5422.3 5687.0 5704.6 5748.6 5765.3 3995.7 4237.7 5027.8 5043.7 5083.0 5387.5 6110.6 6116.5 6128.8 6149.5 3716.5 4051.6 4152.6 4171.5 4217.4 6137.7 6275.0 6303.4 6335.7 6439.8 3695.6 3894.6 4135.3 4137.0 4165.7 4161.5 4352.3 4360.3 4365.9 4389.5 3832.7 4269.0 5048.6 5069.4 5092.6 5393.4 6091.0 6138.8 6155.9 6240.0 1454.3 1476.2 1476.8 1492.7 1493.8 915.1 978.8 981.6 987.6 989.6 5776.6 5795.7 5800.0 5826.7 5840.2 5653.8 5982.3 6252.5 6317.4 6358.2 4754.8 4989.2 5020.5 5055.8 5169.9 3283.5 3322.9 3333.0 3343.4 3346.8 5868.3 6178.0 6506.2 6520.5 6531.4 5995.8 6458.8 6477.0 6484.8 6508.7 6533.0 6569.5 6570.9 6571.4 6596.9 5900.5 6313.7 6390.7 6396.3 6400.9 5501.2 5748.7 6305.9 6320.7 6323.8 4945.8 6086.7 6401.0 6447.1 6584.9 3561.8 4219.8 4249.4 4265.9 4270.7 5610.6 6144.3 6343.2 6422.3 6429.4 4859.0 6113.9 6181.6 6197.8 6205.9 5103.7 5880.1 6169.0 6181.9 6194.2 4923.8 6065.2 6072.0 6147.2 6177.5 5429.9 5723.7 6222.5 6259.8 6267.4 5766.3 5939.8 6039.0 6039.7 6114.5 23:12:25 INFO - PID 1637 | 23:12:25 INFO - PID 1637 | [#5] yui.html Cycles:180 Average:21634.79 Median:18375.54 stddev:30405.93 (165.5%) stddev-sans-first:30450.59 23:12:25 INFO - PID 1637 | Values: 700.3 846.7 860.8 864.1 867.7 13099.3 17765.8 17866.3 18371.3 18371.8 17250.8 17456.9 17639.4 17850.2 17877.3 946.9 959.8 960.1 960.7 961.1 659.6 673.0 954.6 963.5 986.5 1007.9 1036.2 1036.7 1036.8 1038.6 1010.6 1027.3 1028.0 1029.1 1030.6 750.8 758.1 761.8 762.8 764.5 16304.3 16455.7 16792.0 16871.6 17136.0 16304.0 16461.0 16777.7 16870.2 16875.8 13533.1 13732.7 13752.7 13918.4 13949.9 3392.4 3410.5 3419.8 3419.9 3420.4 117757.0 118260.2 120450.4 120466.0 120983.0 10290.9 11063.5 11978.9 12195.8 12200.8 18951.2 24361.6 24530.3 24588.9 24605.8 6714.0 7915.0 7924.0 7950.6 7954.7 3771.2 3865.2 3959.8 3968.2 3976.7 3826.5 3939.3 4176.0 4381.2 4399.0 865.2 887.6 901.2 901.2 903.9 649.9 652.8 657.4 668.9 695.7 10766.1 11311.5 11555.2 11868.6 12075.2 644.8 659.4 666.3 674.5 694.6 5311.8 5325.2 5462.5 5482.0 5561.1 79205.6 108364.5 108764.0 109865.5 110041.0 88532.0 90537.0 92346.0 92705.0 93663.5 83948.0 84179.6 84582.7 85568.0 86360.6 10247.0 10489.9 11025.2 11047.6 11183.6 42694.8 42768.0 43020.6 43215.0 43322.1 56738.1 56773.1 57140.0 57179.4 57577.7 12971.4 16866.7 16872.4 16902.0 16913.7 17441.7 17543.0 18379.3 18478.4 18693.6 16155.3 16470.1 16480.0 16839.2 16945.4 16463.3 16565.3 16843.2 17014.6 17071.7 16041.8 16159.2 16175.7 16179.1 16350.1 11561.0 11603.8 12152.3 12306.4 12306.8 11555.4 12458.8 12461.2 12514.1 12600.5 23:12:25 INFO - PID 1637 | -------- Summary: end -------- 23:12:25 INFO - PID 1637 | 23:12:25 INFO - TEST-INFO | 1637: exit 0 23:12:25 INFO - TEST-OK | dromaeo_css | took 1121355ms 23:12:25 INFO - TEST-START | kraken 23:12:25 INFO - operating with platform_type : mac_ 23:12:25 INFO - Initialising browser for kraken test... 23:12:25 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpdWFHHu/profile http://localhost:49241/getInfo.html) 23:12:28 INFO - PID 1647 | __metrics Screen width/height:1600/1200 23:12:28 INFO - PID 1647 | colorDepth:24 23:12:28 INFO - PID 1647 | Browser inner width/height: 1024/689 23:12:28 INFO - PID 1647 | __metrics 23:12:28 INFO - PID 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:12:28 INFO - PID 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:12:29 INFO - TEST-INFO | 1647: exit 0 23:12:29 INFO - Browser initialized. 23:12:29 INFO - Running cycle 1/1 for kraken test... 23:12:29 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpdWFHHu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:12:41 INFO - PID 1648 | RSS: Main: 242012160 23:12:41 INFO - PID 1648 | 23:12:44 INFO - PID 1648 | 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) 23:12:45 INFO - PID 1648 | RSS: Main: 312766464 23:12:45 INFO - PID 1648 | 23:12:47 INFO - PID 1648 | 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) 23:12:48 INFO - PID 1648 | RSS: Main: 293146624 23:12:48 INFO - PID 1648 | 23:12:51 INFO - PID 1648 | 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) 23:12:52 INFO - PID 1648 | RSS: Main: 387915776 23:12:52 INFO - PID 1648 | 23:12:54 INFO - PID 1648 | 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) 23:12:54 INFO - PID 1648 | RSS: Main: 290054144 23:12:54 INFO - PID 1648 | 23:12:56 INFO - PID 1648 | 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) 23:12:57 INFO - PID 1648 | RSS: Main: 292347904 23:12:57 INFO - PID 1648 | 23:13:00 INFO - PID 1648 | 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) 23:13:01 INFO - PID 1648 | RSS: Main: 356233216 23:13:01 INFO - PID 1648 | 23:13:04 INFO - PID 1648 | 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) 23:13:04 INFO - PID 1648 | RSS: Main: 371081216 23:13:04 INFO - PID 1648 | 23:13:07 INFO - PID 1648 | 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) 23:13:08 INFO - PID 1648 | RSS: Main: 378318848 23:13:08 INFO - PID 1648 | 23:13:09 INFO - PID 1648 | 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) 23:13:10 INFO - PID 1648 | RSS: Main: 333578240 23:13:10 INFO - PID 1648 | 23:13:12 INFO - PID 1648 | 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) 23:13:12 INFO - PID 1648 | RSS: Main: 337199104 23:13:12 INFO - PID 1648 | 23:13:14 INFO - PID 1648 | 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) 23:13:15 INFO - PID 1648 | RSS: Main: 338378752 23:13:15 INFO - PID 1648 | 23:13:18 INFO - PID 1648 | 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) 23:13:19 INFO - PID 1648 | RSS: Main: 336007168 23:13:19 INFO - PID 1648 | 23:13:22 INFO - PID 1648 | 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) 23:13:22 INFO - PID 1648 | RSS: Main: 346116096 23:13:22 INFO - PID 1648 | 23:13:24 INFO - PID 1648 | 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) 23:13:25 INFO - PID 1648 | RSS: Main: 388644864 23:13:25 INFO - PID 1648 | 23:13:25 INFO - PID 1648 | __start_tp_report 23:13:25 INFO - PID 1648 | _x_x_mozilla_page_load 23:13:25 INFO - PID 1648 | _x_x_mozilla_page_load_details 23:13:25 INFO - PID 1648 | |i|pagename|runs| 23:13:25 INFO - PID 1648 | |0;ai-astar;112;111;109;112;112;110;112;112;112;112 23:13:25 INFO - PID 1648 | |1;audio-beat-detection;141;114;106;148;110;108;108;103;141;139 23:13:25 INFO - PID 1648 | |2;audio-dft;188;184;185;187;186;183;183;183;183;184 23:13:25 INFO - PID 1648 | |3;audio-fft;81;59;58;86;60;58;61;60;83;61 23:13:25 INFO - PID 1648 | |4;audio-oscillator;93;70;87;69;69;70;91;69;69;89 23:13:25 INFO - PID 1648 | |5;imaging-gaussian-blur;96;131;90;90;90;89;89;90;90;89 23:13:25 INFO - PID 1648 | |6;imaging-darkroom;96;96;96;96;95;96;97;95;96;96 23:13:25 INFO - PID 1648 | |7;imaging-desaturate;85;85;83;85;86;86;84;85;86;85 23:13:25 INFO - PID 1648 | |8;json-parse-financial;53;50;51;49;50;50;50;49;48;50 23:13:25 INFO - PID 1648 | |9;json-stringify-tinderbox;54;60;69;55;60;62;59;56;58;57 23:13:25 INFO - PID 1648 | |10;stanford-crypto-aes;82;83;83;83;83;84;80;83;83;83 23:13:25 INFO - PID 1648 | |11;stanford-crypto-ccm;137;139;137;140;136;136;136;138;195;145 23:13:25 INFO - PID 1648 | |12;stanford-crypto-pbkdf2;189;183;181;184;186;183;186;182;183;184 23:13:25 INFO - PID 1648 | |13;stanford-crypto-sha256-iterative;69;70;74;92;73;68;67;67;68;67 23:13:25 INFO - PID 1648 | __end_tp_report 23:13:25 INFO - PID 1648 | __start_cc_report 23:13:25 INFO - PID 1648 | _x_x_mozilla_cycle_collect,2243 23:13:25 INFO - PID 1648 | __end_cc_report 23:13:25 INFO - PID 1648 | __startTimestamp1492582405002__endTimestamp 23:13:25 INFO - PID 1648 | 23:13:25 INFO - PID 1648 | ------- Summary: start ------- 23:13:25 INFO - PID 1648 | Number of tests: 14 23:13:25 INFO - PID 1648 | 23:13:25 INFO - PID 1648 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:1.07 (1.0%) stddev-sans-first:1.12 23:13:25 INFO - PID 1648 | Values: 112.0 111.0 109.0 112.0 112.0 110.0 112.0 112.0 112.0 112.0 23:13:25 INFO - PID 1648 | 23:13:25 INFO - PID 1648 | [#1] audio-beat-detection Cycles:10 Average:121.80 Median:126.50 stddev:17.96 (14.2%) stddev-sans-first:17.66 23:13:25 INFO - PID 1648 | Values: 141.0 114.0 106.0 148.0 110.0 108.0 108.0 103.0 141.0 139.0 23:13:25 INFO - PID 1648 | 23:13:25 INFO - PID 1648 | [#2] audio-dft Cycles:10 Average:184.60 Median:184.50 stddev:1.84 (1.0%) stddev-sans-first:1.48 23:13:25 INFO - PID 1648 | Values: 188.0 184.0 185.0 187.0 186.0 183.0 183.0 183.0 183.0 184.0 23:13:25 INFO - PID 1648 | 23:13:25 INFO - PID 1648 | [#3] audio-fft Cycles:10 Average:66.70 Median:61.00 stddev:11.59 (19.0%) stddev-sans-first:11.07 23:13:25 INFO - PID 1648 | Values: 81.0 59.0 58.0 86.0 60.0 58.0 61.0 60.0 83.0 61.0 23:13:25 INFO - PID 1648 | 23:13:25 INFO - PID 1648 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:78.50 stddev:10.78 (13.7%) stddev-sans-first:9.89 23:13:25 INFO - PID 1648 | Values: 93.0 70.0 87.0 69.0 69.0 70.0 91.0 69.0 69.0 89.0 23:13:25 INFO - PID 1648 | 23:13:25 INFO - PID 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:94.40 Median:90.00 stddev:13.02 (14.5%) stddev-sans-first:13.80 23:13:25 INFO - PID 1648 | Values: 96.0 131.0 90.0 90.0 90.0 89.0 89.0 90.0 90.0 89.0 23:13:25 INFO - PID 1648 | 23:13:25 INFO - PID 1648 | [#6] imaging-darkroom Cycles:10 Average:95.90 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 23:13:25 INFO - PID 1648 | Values: 96.0 96.0 96.0 96.0 95.0 96.0 97.0 95.0 96.0 96.0 23:13:25 INFO - PID 1648 | 23:13:25 INFO - PID 1648 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.00 stddev:0.94 (1.1%) stddev-sans-first:1.00 23:13:25 INFO - PID 1648 | Values: 85.0 85.0 83.0 85.0 86.0 86.0 84.0 85.0 86.0 85.0 23:13:25 INFO - PID 1648 | 23:13:25 INFO - PID 1648 | [#8] json-parse-financial Cycles:10 Average:50.00 Median:50.00 stddev:1.33 (2.7%) stddev-sans-first:0.87 23:13:25 INFO - PID 1648 | Values: 53.0 50.0 51.0 49.0 50.0 50.0 50.0 49.0 48.0 50.0 23:13:25 INFO - PID 1648 | 23:13:25 INFO - PID 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.50 stddev:4.29 (7.2%) stddev-sans-first:4.16 23:13:25 INFO - PID 1648 | Values: 54.0 60.0 69.0 55.0 60.0 62.0 59.0 56.0 58.0 57.0 23:13:25 INFO - PID 1648 | 23:13:25 INFO - PID 1648 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:83.00 stddev:1.06 (1.3%) stddev-sans-first:1.09 23:13:25 INFO - PID 1648 | Values: 82.0 83.0 83.0 83.0 83.0 84.0 80.0 83.0 83.0 83.0 23:13:25 INFO - PID 1648 | 23:13:25 INFO - PID 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:143.90 Median:138.50 stddev:18.16 (13.1%) stddev-sans-first:19.09 23:13:25 INFO - PID 1648 | Values: 137.0 139.0 137.0 140.0 136.0 136.0 136.0 138.0 195.0 145.0 23:13:25 INFO - PID 1648 | 23:13:25 INFO - PID 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.10 Median:184.00 stddev:2.33 (1.3%) stddev-sans-first:1.67 23:13:25 INFO - PID 1648 | Values: 189.0 183.0 181.0 184.0 186.0 183.0 186.0 182.0 183.0 184.0 23:13:25 INFO - PID 1648 | 23:13:25 INFO - PID 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.50 Median:69.50 stddev:7.62 (11.0%) stddev-sans-first:8.03 23:13:25 INFO - PID 1648 | Values: 69.0 70.0 74.0 92.0 73.0 68.0 67.0 67.0 68.0 67.0 23:13:25 INFO - PID 1648 | -------- Summary: end -------- 23:13:25 INFO - PID 1648 | 23:13:26 INFO - TEST-INFO | 1648: exit 0 23:13:26 INFO - TEST-OK | kraken | took 60361ms 23:13:26 INFO - SUITE-END | took 1181s 23:13:26 INFO - Completed test suite (00:19:42) 23:13:26 INFO - javascript benchmark 23:13:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7691.064130310344, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3505.6143824179344, 3528.7529872908876, 3532.7527073104875, 3555.2533967866757, 3565.9643403565956, 6058.4850287725485, 6181.320054793969, 6263.185785847845, 6282.68586570672, 6302.369763023701, 6167.845803854906, 6173.598716083451, 6224.502039836802, 6228.439440450358, 6247.12540244365, 5242.0, 5429.4570542945785, 5436.3476382834115, 5439.401665816757, 5489.0, 5520.558355331564, 5532.6680399175975, 5550.91673624896, 5570.053090974544, 5615.989121958046, 5309.150535914274, 5414.972925135349, 5424.213489044067, 5427.402985671573, 5464.480874316933, 5459.290292262021, 5495.093309603911, 5500.119980803052, 5501.422350653163, 5572.219889215513, 5384.838454846361, 5413.729313534308, 5440.9934162180125, 5467.043267428224, 5484.095124304525, 6071.0, 6141.24053858259, 6143.972489078594, 6146.231721034871, 6247.187865577492, 6662.133922590032, 6665.566738162004, 6676.065350850885, 6678.632675202862, 6680.23177334603, 6756.0, 6756.2568117507035, 6774.5257831951785, 6776.593404395752, 6776.9322306777285, 6145.047517634774, 6242.344553821783, 6258.061290806416, 6323.36766323364, 6333.018382150774, 6570.868582628321, 6768.9661551691925, 6843.828904277333, 6889.276625954303, 6916.0, 6427.775027874002, 6437.324080971431, 6477.676116194171, 6555.934440655532, 6578.210614726264, 6670.299618540081, 6735.360140786617, 6753.425958793459, 6754.358335958076, 6768.458523318122, 6631.30371311015, 6646.8005959821285, 6650.567713098633, 6653.2681405045405, 6655.334466553306, 5378.91931621026, 5382.838514844561, 6371.23545174582, 6392.776252831129, 6460.515461340418, 5279.102552566073, 5289.841304760864, 5291.285676433709, 5291.973540132351, 5301.814436494704, 4661.394018777605, 4678.3216433616935, 4771.045790841846, 4776.808927642855, 4778.641601879873, 6125.142480052797, 6168.321484636686, 6170.0, 6182.1345011698395, 6184.783532576338, 5609.691466969274, 6524.543281970311, 6645.302243264484, 6743.831404214836, 6744.393004629509, 6232.376762323732, 6263.96868015657, 6286.0, 6287.088372186099, 6292.244930608264, 5857.6192547485125, 5875.059990401601, 5894.734736936848, 5895.086261629454, 5946.524278057745, 6558.672066396756, 6637.0, 6653.900191497035, 6809.977108068793, 6870.003849441903, 5840.2991641003355, 6551.050097735897, 6650.102236198051, 6749.493787965824, 6791.830204244933, 6718.630475323904, 6862.622555759481, 6864.691088900911, 6878.55289406187, 6879.3120687930805, 6374.1394911688185, 6604.7027883746305, 6642.269350371552, 6644.734210631714, 6647.60114393138, 5361.571074313967, 6535.411812936668, 6543.0, 6546.672666366758, 6546.738130474918, 6362.586431881913, 6380.776672816429, 6386.457151142019, 6386.840328991812, 6426.196725409324, 6477.51418643613, 6499.415052645285, 6505.967470162619, 6507.479401647952, 6530.020496925499, 6234.532410069353, 6412.487001039999, 6414.743410263537, 6424.421802037839, 6430.260520040255, 6319.936800632118, 6508.73965041412, 6536.934630653823, 6540.313267106885, 6548.377904099103, 5362.7586758596735, 6309.4952403806665, 6329.208848893888, 6333.2716737575765, 6362.427381535684], "value": 6059.7154073007805, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [782.2451334462243, 800.9399295052871, 1109.522905150785, 1132.790433769752, 1135.4152611405134, 28465.715342846564, 36597.0, 36896.63103368966, 36930.07674808128, 36932.81533592335, 38454.61545384545, 38790.41814372787, 38830.61169388305, 38830.64092756754, 38881.80559097208, 6059.969700151493, 6218.347073557279, 8478.364122690808, 8630.611622476972, 8633.69782057628, 8367.58162091896, 8524.701635442761, 8532.61603227853, 8564.700235491759, 8591.312694984386, 8552.0, 8615.870761938577, 8622.827543449128, 8630.352723545728, 8654.826903461926, 8504.702335418293, 8522.914770852305, 8580.570971451403, 8584.656613735444, 8589.28214357131, 8391.244787969112, 8461.492310461392, 8462.196091371372, 8466.195711407407, 8469.872951905727, 36484.45273320902, 36651.08372290687, 36807.63192368069, 36812.44781328282, 36832.895013149646, 3546.4503002034467, 3577.266660334625, 3581.5702115746276, 3585.1574879903465, 3585.2470981094, 24166.637500437508, 30306.393872122655, 30322.54516182259, 30412.239694007825, 30437.391252175054, 3098.98450507746, 3127.6246850377875, 3147.6065491813524, 3158.463061279565, 3164.841757912095, 271534.0, 278227.6088619564, 278661.0, 278670.0, 279439.0, 32329.0, 32372.514412283836, 38533.03667408281, 47495.287570685774, 47789.80525486821, 39796.80609581717, 41942.370864437056, 47818.28272575914, 48614.0, 49336.50663493319, 13458.259795711134, 13565.050446468653, 13567.1859688419, 13591.252481113435, 13596.252206128758, 9435.6697515588, 9585.0, 11017.008469237648, 11049.668509944715, 11075.72310692239, 11564.074874010059, 11627.709307267385, 11754.942055215071, 11760.647180584425, 11800.763984720257, 4686.250199967989, 4992.976439829826, 5037.773300201499, 5366.6243362965, 5459.945400545944, 996.4220751963844, 1035.316690983947, 1044.4099084017505, 1053.1832588784287, 1075.9677209683723, 18375.632487350445, 22617.869106544607, 23775.286741397787, 23822.404439889142, 23887.880560597085, 1012.7569383347943, 1116.748731535398, 1151.6372342712018, 1153.1985270237271, 1154.3477934966718, 10394.856565777758, 10700.839487407695, 12126.454309556091, 12171.0, 12229.571964981184, 278300.0, 278656.0, 278680.0, 279205.0, 279678.0, 51259.23111153336, 51314.0, 51320.74339628278, 51386.48613513892, 51418.74290628598, 1332.2938842413537, 1477.6232060824636, 1510.4864346122151, 1514.4547962733627, 1515.4695856450376, 21905.452363691034, 23036.193733219257, 23237.76762232356, 23240.4189895254, 23262.418439539146, 31121.221969450944, 35279.94160175199, 36454.8177259112, 36546.451803222444, 36555.81722091373, 260541.0, 261070.69464652555, 262765.0, 262950.6852465802, 263060.0, 28588.857055714587, 29040.419191615627, 32699.509507356932, 33406.49890251697, 33500.49749253812, 42223.366649499665, 43068.78465607652, 43136.35295470633, 43159.568404315556, 43490.0, 28159.859200703864, 28939.42121157607, 29406.970756023435, 30133.69866301309, 30197.0, 24959.87520062388, 29597.556036659036, 29765.0, 29993.850030749705, 30090.24774380658, 35503.28993420169, 35508.46737298891, 35529.28941421105, 35667.57329706783, 35694.0, 23273.650895236922, 28093.719062809927, 28993.275168120123, 29195.124146275644, 29767.702322976493, 21475.677864831727, 21476.556789306673, 23227.419314517272, 23466.826658667338, 23564.410889727893], "value": 16663.58687331926, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.8207261913684, 425.1412147462128, 426.413681188366, 433.8047878454695, 447.2039769210806, 19376.806231937677, 21038.684419733698, 21971.560568788656, 22047.66928496074, 22058.897055147238, 21378.57242855146, 22143.335699928986, 22554.88722556389, 22625.094996200172, 22914.770852291473, 6076.422739839719, 6372.1397611322345, 6372.330905254951, 6378.744850205986, 6385.074164246182, 5916.585838991272, 6224.190855188842, 6336.144620476222, 6339.112524246586, 6356.046593011062, 5982.102684597324, 6280.874382512347, 6294.150289710899, 6304.621722696653, 6306.463950564207, 6210.596311239778, 6321.589096708699, 6324.241091069055, 6325.462335701425, 6328.873422531569, 5535.5715427657715, 5589.860253493654, 5617.269754931833, 5625.09998400254, 5657.12314591239, 27124.59313110305, 27209.72790272092, 27777.861110694317, 27854.442911141665, 28146.437071258664, 2215.612267853119, 2286.039863257436, 2287.4738810073777, 2290.335802617239, 2292.988535057331, 17602.647947040987, 17682.0, 17868.0, 18226.544336391562, 18437.63124737498, 2126.6384714598557, 2133.765285818559, 2174.913003479859, 2192.7478339990785, 2197.439652888519, 154757.0, 157980.21009894993, 160011.0, 161268.1936590321, 162936.18531907382, 10397.0, 10908.727281817859, 11077.556897724162, 11765.352905590104, 11958.40207989597, 16355.345786168658, 16356.918215408847, 16373.0, 16375.508734737978, 16387.508374748777, 8096.8380632387025, 8096.987876515435, 8402.243798058082, 8575.228229459379, 8577.185167409087, 7176.9952206691105, 7778.416618753616, 7826.491278066907, 7842.254985776342, 8027.638756255866, 6745.494087943423, 7359.852802943911, 7492.325690687864, 7502.4748267621835, 7527.435442341846, 1965.496395257629, 2030.6902048178843, 2167.913283468675, 2174.3476956913178, 2216.1246307708366, 915.4415806358116, 952.538019060755, 982.0866594067586, 994.2741798487, 997.3018886779283, 16578.0, 19210.51973700641, 20394.69407958882, 20630.38108856707, 21066.946652667306, 999.1008092716613, 1009.268280496634, 1017.699778565325, 1021.2340744441668, 1037.5123691864812, 10291.639792607222, 10701.625443109453, 10709.518071686793, 10720.46397680113, 10907.854675259143, 550549.0, 553298.0, 558097.0, 573538.0, 573673.0, 102533.48733256286, 106996.79009629723, 125973.0, 126720.46559068767, 126946.0, 1145.5188820695328, 1152.095604950127, 1192.7793358228755, 1193.6299747078433, 1194.599809063956, 16753.581160471087, 17539.0, 17628.29486820564, 17904.91047544754, 17972.820271797114, 53927.0, 54283.0, 55363.72318138384, 56337.0, 56629.43370566241, 76873.61563192148, 78104.0, 78790.60604696939, 81553.0, 82306.3538729234, 7830.960845195737, 8713.43362681449, 8981.1467910551, 9018.0, 9069.77325566866, 11246.887531124585, 11927.880721192678, 11984.801519847946, 13452.932735336652, 14631.0, 11478.081753459535, 11710.414479276173, 12017.699557510783, 12019.579314723942, 12086.697832554257, 10876.564937402416, 11089.445527723421, 11266.94366528162, 11320.547178112784, 11696.941515292368, 11927.582534611247, 11943.642979664195, 12049.939750301193, 12164.2701437914, 12220.877791221974, 8646.221840034426, 8655.82688346217, 8775.912240877766, 9094.272458203195, 9094.58993366745, 9618.951905240428, 11844.170908036354, 11978.520859165536, 12069.583573974754, 12165.513379464723], "value": 10296.167496969467, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1168.5442677355827, 1306.2032160382166, 1373.182956141096, 1439.3379045639006, 1465.5237047959408, 9741.805163896726, 10231.48842557873, 11377.715557111076, 11735.178537502377, 11899.881001189986, 11568.652940411781, 11751.588694395698, 11770.705732356695, 11773.470193841276, 11774.470148843302, 2390.509945461185, 2427.672264244322, 2433.2578563538113, 2437.683101196842, 2445.0953147335513, 2016.5765189310189, 2102.0435701755664, 2102.453362125843, 2107.7049213110176, 2400.435897564071, 2074.543600407908, 2342.0865862313713, 2418.588839897213, 2439.5364880672614, 2441.4994926040117, 2023.5952809438097, 2391.796697280733, 2393.4734358441115, 2393.940151496209, 2406.205952035824, 2334.0663734506165, 2346.0146738369926, 2382.29722231942, 2394.0304176808245, 2399.400149962509, 11588.420578971018, 11670.35813030283, 11723.003544698711, 11726.061915046776, 11726.35505047222, 5406.864828379322, 6731.764388246388, 6750.561213521106, 6758.594484330956, 6767.4924380671155, 3858.749181303206, 4064.979675101635, 4250.91498170038, 4251.489821221473, 4254.808533615995, 1097.7914196302677, 1247.8377810884526, 1249.993750031253, 1296.3259105265208, 1301.5119330251157, 16612.00327980325, 16644.58388540284, 16665.416710415077, 16667.0, 16668.83331166697, 5039.546440820343, 5566.415526369754, 5848.093545500455, 5962.945197265814, 5984.670843103584, 13764.036517443683, 13767.38046787897, 13773.586792396243, 13793.0, 13836.86163138376, 6701.530892837454, 6712.597244165366, 6713.832154196184, 6730.629815360202, 6739.123913891162, 6078.848028799227, 6131.233595800525, 6149.41580549847, 6194.659293738798, 6256.968715156395, 3775.4714339992424, 4059.2490389278078, 4500.310520432245, 4575.816967321336, 4638.350630911675, 1295.838020247469, 1308.8625694302152, 1416.5042235217797, 1453.2588379926103, 1453.4767483705857, 716.7133146741241, 791.0941971442666, 793.4683761879554, 810.0643756461296, 812.5977641067709, 6629.867402652017, 6917.861642767117, 6920.930790692129, 7027.262137475492, 7064.293570642997, 909.4725059465494, 937.8124375125002, 939.7603611079131, 943.5188054092463, 953.9761505962267, 3726.2733766915735, 4159.5008598967715, 4356.912861742748, 4366.847160349373, 4480.708753930984, 14667.19330436815, 15338.923305383401, 15353.385864565293, 15360.231988400536, 15380.230988450532, 11648.301101933912, 15234.085954842745, 15237.619059523378, 15243.314050867737, 15264.771028434583, 8485.0, 8714.52070136136, 8719.215270625547, 8735.475871447734, 8768.991565969867, 6989.860202796017, 7001.0, 7166.247544007804, 7169.713211471482, 7222.09723784527, 13115.606531804062, 13572.457101716216, 13644.0, 13656.0, 13734.175949443066, 13435.044290256128, 13798.241096739775, 13802.65493362674, 13903.096298740955, 14112.576622701336, 9407.247420206504, 9722.27082968766, 9739.610415583298, 9739.659111931049, 9743.951280243553, 10664.466776661291, 10750.408727520062, 10863.67408977732, 10874.0, 10911.963363480456, 7319.414446844133, 9262.0, 9524.523773811143, 9802.313838031268, 9858.753531161778, 8959.1936725695, 9001.729948101567, 9355.532223388667, 9356.906430935876, 9779.85330220033, 6883.621400823003, 8168.918310816815, 8337.082920878578, 8338.708145214889, 8362.540060296742, 6021.0, 6108.602940808833, 6207.782727604512, 6277.121203031488, 6355.396237357444, 5975.37258587842, 6076.058210977306, 6259.937400625936, 6293.213348331458, 6296.779612713533], "value": 5391.518184675227, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.48038407957216, 401.5181781861766, 410.0568692008381, 410.5607000509454, 410.833612386983, 5907.970460147699, 5930.762769489226, 5931.377205393436, 5968.612040217388, 5999.0, 5154.60825577094, 5648.519875810561, 5811.447912448321, 5826.0, 5856.677882716449, 4299.957000430002, 4300.623800383878, 4310.676699247553, 4316.84891028814, 4324.97837510812, 3561.9109522261892, 4323.805428755698, 4327.2643650579475, 4344.652427805768, 4360.0, 4147.4815648044, 4275.337322714984, 4299.742015479082, 4302.526722060571, 4322.595156574111, 3506.7895926244505, 4245.87262382129, 4258.936115958279, 4265.850695225668, 4266.658667306608, 3459.2735525539665, 3522.7710198836994, 3715.665590096877, 3765.9623403765886, 3772.7170462215445, 5422.322209723785, 5686.981121701819, 5704.572157088235, 5748.5975981681295, 5765.250517432707, 3995.6603688686496, 4237.665135482313, 5027.798888044474, 5043.672161309503, 5082.974585127087, 5387.4612538746205, 6110.602810817282, 6116.541259405518, 6128.846778830565, 6149.5080393568405, 3716.535433070866, 4051.574584668597, 4152.647025002879, 4171.478565179353, 4217.36739489076, 6137.6624285664275, 6275.0, 6303.432691057781, 6335.68321583919, 6439.751602443993, 3695.593484716679, 3894.5521265054313, 4135.29699951009, 4137.034908418477, 4165.687573432005, 4161.479815023122, 4352.260115780325, 4360.302351623725, 4365.934510982338, 4389.4513185851765, 3832.6742226911024, 4269.018125831014, 5048.646594738333, 5069.366329208849, 5092.6435149539175, 5393.352797664305, 6091.02543593023, 6138.785142519991, 6155.907661385083, 6239.96880015597, 1454.3092031285266, 1476.2028504607392, 1476.8301645310926, 1492.731308364483, 1493.7983372244826, 915.0854528327299, 978.8238117138841, 981.5681100315838, 987.629638885418, 989.6189966862665, 5776.631581051749, 5795.652260864362, 5799.984008315652, 5826.70866456684, 5840.240768700041, 5653.830385088454, 5982.341942386334, 6252.499800015988, 6317.368263173737, 6358.1734374531015, 4754.833580824655, 4989.201727723546, 5020.472850350734, 5055.772490237875, 5169.948300517022, 3283.4910588858766, 3322.850471728778, 3333.000099969999, 3343.38147442719, 3346.849391777376, 5868.3251426086545, 6178.0, 6506.154199953976, 6520.543561950618, 6531.444827189647, 5995.760169593254, 6458.838529036811, 6477.0, 6484.837879053061, 6508.695413238196, 6533.0, 6569.4744420445295, 6570.897312859935, 6571.441427478718, 6596.86806263872, 5900.468957793648, 6313.715882785194, 6390.7443702251385, 6396.264429590657, 6400.903986440114, 5501.229827824188, 5748.683822389641, 6305.905411418924, 6320.652364119834, 6323.810285691437, 4945.77744001528, 6086.695665216633, 6401.0, 6447.065175549613, 6584.901226481511, 3561.8041007743786, 4219.789010549522, 4249.447571815768, 4265.944855346424, 4270.743755374563, 5610.551155907436, 6144.262688477412, 6343.238811342669, 6422.2614399344675, 6429.421352078335, 4858.95141048585, 6113.938860611337, 6181.629102253879, 6197.783077592263, 6205.906911396242, 5103.744812759422, 5880.14737863016, 6169.020981466839, 6181.938180618316, 6194.225721784777, 4923.793670550738, 6065.241080086726, 6072.0, 6147.231596050494, 6177.536684748572, 5429.891402172014, 5723.713814309352, 6222.471089957405, 6259.78090766821, 6267.435930766253, 5766.3368712598585, 5939.821805345847, 6038.969805150946, 6039.698015099315, 6114.510839132948], "value": 4488.676439892344, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.3486757315694, 846.7417437681506, 860.8321377331423, 864.0797550608597, 867.6529388244701, 13099.279539625317, 17765.822341776577, 17866.28534858604, 18371.265149394043, 18371.81628183718, 17250.82749172508, 17456.912715436403, 17639.382621608245, 17850.196741146647, 17877.28490860364, 946.9147776700113, 959.7600599850037, 960.13108137136, 960.7309953213077, 961.1301771896445, 659.6240143118424, 673.0308355967416, 954.613381580461, 963.4652767713916, 986.4968865878418, 1007.9344842585208, 1036.1607098250404, 1036.6630844975375, 1036.7511797168663, 1038.584566173533, 1010.5806090472445, 1027.337367398024, 1027.9743006424862, 1029.0532709906904, 1030.567161792049, 750.7785203365021, 758.1243663568532, 761.7828918758182, 762.8092976755811, 764.533634482965, 16304.347826086941, 16455.67088658232, 16792.0, 16871.578210544834, 17135.971841689538, 16304.0, 16461.0, 16777.664446710998, 16870.240839162267, 16875.83124168755, 13533.052686311961, 13732.65668358289, 13752.65618359539, 13918.443262269497, 13949.860501394958, 3392.423288041039, 3410.5225268462436, 3419.829008549563, 3419.880304189366, 3420.3672320620763, 117757.0, 118260.22609660862, 120450.39774801157, 120466.0, 120983.0, 10290.948545257226, 11063.502142403611, 11978.940105299418, 12195.817062744067, 12200.816987745191, 18951.24195032211, 24361.63457548138, 24530.264092076908, 24588.87705561461, 24605.753942460706, 6714.0, 7915.0, 7924.0, 7950.6422211000645, 7954.721584744622, 3771.245750849841, 3865.207632435344, 3959.8416063357145, 3968.206358728266, 3976.681865450757, 3826.4834247376803, 3939.330313846653, 4176.0, 4381.2332841753205, 4399.010222699867, 865.16079402979, 887.6405055952349, 901.193431878465, 901.2204443156622, 903.9107875010596, 649.9025146228104, 652.807421810567, 657.3984803904469, 668.9331066893271, 695.7321431248904, 10766.084882784895, 11311.490982905789, 11555.191136620524, 11868.643940681794, 12075.215111017756, 644.7807745366598, 659.4295934017041, 666.3070406776898, 674.4806498995746, 694.6110178300169, 5311.814086506954, 5325.174597937326, 5462.453754624586, 5482.0406428875585, 5561.054620714488, 79205.60397198092, 108364.45817770861, 108764.0, 109865.45067274613, 110041.0, 88532.0, 90537.0, 92346.0, 92705.0, 93663.53168234116, 83948.0, 84179.5791021041, 84582.73125903116, 85568.0, 86360.56819715862, 10246.957981178635, 10489.947550262199, 11025.228234023862, 11047.613333533287, 11183.608573699881, 42694.78652606717, 42768.0, 43020.56979430166, 43215.0, 43322.133557328045, 56738.14892776694, 56773.148402774816, 57140.0, 57179.426925789274, 57577.71211143918, 12971.41628626733, 16866.74699879527, 16872.409465668643, 16902.0, 16913.74629380585, 17441.651166976844, 17543.035133067802, 18379.26482940721, 18478.445646630622, 18693.626127477648, 16155.27301271422, 16470.09414482167, 16480.0, 16839.24223409974, 16945.406910758065, 16463.34146634121, 16565.25456354491, 16843.1578421076, 17014.57463563421, 17071.658566828344, 16041.759373609639, 16159.192040398168, 16175.67648647044, 16179.110148941922, 16350.100744459169, 11561.0, 11603.825942611036, 12152.270863748203, 12306.384680765746, 12306.75386492283, 11555.364454955059, 12458.813117803422, 12461.1900226485, 12514.12401131912, 12600.495980160691], "value": 8225.488524591095, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1428.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 109.0, 112.0, 112.0, 110.0, 112.0, 112.0, 112.0, 112.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 114.0, 106.0, 148.0, 110.0, 108.0, 108.0, 103.0, 141.0, 139.0], "value": 121.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 184.0, 185.0, 187.0, 186.0, 183.0, 183.0, 183.0, 183.0, 184.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 58.0, 86.0, 60.0, 58.0, 61.0, 60.0, 83.0, 61.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 70.0, 87.0, 69.0, 69.0, 70.0, 91.0, 69.0, 69.0, 89.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 131.0, 90.0, 90.0, 90.0, 89.0, 89.0, 90.0, 90.0, 89.0], "value": 94.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 97.0, 95.0, 96.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 83.0, 85.0, 86.0, 86.0, 84.0, 85.0, 86.0, 85.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 51.0, 49.0, 50.0, 50.0, 50.0, 49.0, 48.0, 50.0], "value": 50.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 60.0, 69.0, 55.0, 60.0, 62.0, 59.0, 56.0, 58.0, 57.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 83.0, 83.0, 83.0, 84.0, 80.0, 83.0, 83.0, 83.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 137.0, 140.0, 136.0, 136.0, 136.0, 138.0, 195.0, 145.0], "value": 143.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 183.0, 181.0, 184.0, 186.0, 183.0, 186.0, 182.0, 183.0, 184.0], "value": 184.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 74.0, 92.0, 73.0, 68.0, 67.0, 67.0, 68.0, 67.0], "value": 71.5, "unit": "score"}]}]} 23:13:26 INFO - Return code: 0 23:13:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:13:26 ERROR - # TBPL SUCCESS # 23:13:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:13:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:13:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:13:26 INFO - 2017-04-18 23:13:26.820 screenresolution[1653:9960] starting screenresolution argv=screenresolution get 23:13:26 INFO - 2017-04-18 23:13:26.822 screenresolution[1653:9960] Display 0: 1600x1200x32@60 23:13:26 INFO - 2017-04-18 23:13:26.828 screenresolution[1654:9964] starting screenresolution argv=screenresolution list 23:13:26 INFO - Available Modes on Display 0 23:13:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:13:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:13:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:13:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:13:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:13:27 INFO - Intel Iris: 23:13:27 INFO - Chipset Model: Intel Iris 23:13:27 INFO - Type: GPU 23:13:27 INFO - Bus: Built-In 23:13:27 INFO - VRAM (Dynamic, Max): 1536 MB 23:13:27 INFO - Vendor: Intel (0x8086) 23:13:27 INFO - Device ID: 0x0a2e 23:13:27 INFO - Revision ID: 0x0009 23:13:27 INFO - Displays: 23:13:27 INFO - Display: 23:13:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:13:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:13:27 INFO - Display Serial Number: 1600x1200 60 23:13:27 INFO - Main Display: Yes 23:13:27 INFO - Mirror: Off 23:13:27 INFO - Online: Yes 23:13:27 INFO - Rotation: Supported 23:13:27 INFO - Return code: 0 23:13:27 INFO - Running post-action listener: _resource_record_post_action 23:13:27 INFO - [mozharness: 2017-04-19 06:13:27.063871Z] Finished run-tests step (success) 23:13:27 INFO - Running post-run listener: _resource_record_post_run 23:13:27 INFO - Total resource usage - Wall time: 1202s; CPU: 53.0%; Read bytes: 39437824; Write bytes: 514979840; Read time: 389; Write time: 2004 23:13:27 INFO - TinderboxPrint: CPU usage
53.2% 23:13:27 INFO - TinderboxPrint: I/O read bytes / time
39,437,824 / 389 23:13:27 INFO - TinderboxPrint: I/O write bytes / time
514,979,840 / 2,004 23:13:27 INFO - TinderboxPrint: CPU idle
2,250.8 (46.8%) 23:13:27 INFO - TinderboxPrint: CPU system
956.8 (19.9%) 23:13:27 INFO - TinderboxPrint: CPU user
1,602.0 (33.3%) 23:13:27 INFO - TinderboxPrint: Swap in / out
247,320,576 / 0 23:13:27 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 209719808; Write bytes: 212355072; Read time: 16176; Write time: 442 23:13:27 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 34461184; Write bytes: 301035520; Read time: 348; Write time: 1556 23:13:27 INFO - Running post-run listener: _upload_blobber_files 23:13:27 INFO - Blob upload gear active. 23:13:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:13:27 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 23:13:27 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:13:27 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:13:27 INFO - (blobuploader) - INFO - Open directory for files ... 23:13:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:13:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:13:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:13:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:13:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:13:28 INFO - (blobuploader) - INFO - Done attempting. 23:13:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:13:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:13:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:13:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:13:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:13:29 INFO - (blobuploader) - INFO - Done attempting. 23:13:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 23:13:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:13:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:13:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 23:13:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:13:30 INFO - (blobuploader) - INFO - Done attempting. 23:13:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:13:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:13:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:13:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:13:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:13:32 INFO - (blobuploader) - INFO - Done attempting. 23:13:32 INFO - (blobuploader) - INFO - Iteration through files over. 23:13:32 INFO - Return code: 0 23:13:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:13:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:13:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6b844fa27f06955218f6873015c08d3967089ff504b9f05df2b3b6a81ba50c8d67d2e5715f315bb50eca59d63092bbd450d9370023c3b0d0236277e804c9c83b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ed2a3f8760892f61df8998252a35a4381dc61dd700e88b49bd2927f863a2ebc47bbb9568e6d2c1f477fcb3b47cd0e70af7cc4996a2098339b1324f89421afd23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6d27ea4df5e00427f26270033145173dccae119b46efca0fe38c7242406629d0248ac409c91c263e0f576671bee7d8add130616d375a88bfb516f765ce7b68d8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b2ecd5eda6c33f22210e5cf8b002ec008653464d25173e05a863387319248babcd24e75ff99021229372f2b558e007285c2cc514209f068151fc863f3f9b21f9"} 23:13:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:13:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:13:32 INFO - Contents: 23:13:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6b844fa27f06955218f6873015c08d3967089ff504b9f05df2b3b6a81ba50c8d67d2e5715f315bb50eca59d63092bbd450d9370023c3b0d0236277e804c9c83b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ed2a3f8760892f61df8998252a35a4381dc61dd700e88b49bd2927f863a2ebc47bbb9568e6d2c1f477fcb3b47cd0e70af7cc4996a2098339b1324f89421afd23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6d27ea4df5e00427f26270033145173dccae119b46efca0fe38c7242406629d0248ac409c91c263e0f576671bee7d8add130616d375a88bfb516f765ce7b68d8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b2ecd5eda6c33f22210e5cf8b002ec008653464d25173e05a863387319248babcd24e75ff99021229372f2b558e007285c2cc514209f068151fc863f3f9b21f9"} 23:13:32 INFO - Running post-run listener: copy_logs_to_upload_dir 23:13:32 INFO - Copying logs to upload dir... 23:13:32 INFO - mkdir: /builds/slave/test/build/upload/logs 23:13:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.039153 ========= master_lag: 0.10 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-18 23:13:32.940421) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-18 23:13:32.945095) ========= 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.PT3NkkqzUs/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.96K9bhmIlw/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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/Pine/sha512/6b844fa27f06955218f6873015c08d3967089ff504b9f05df2b3b6a81ba50c8d67d2e5715f315bb50eca59d63092bbd450d9370023c3b0d0236277e804c9c83b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ed2a3f8760892f61df8998252a35a4381dc61dd700e88b49bd2927f863a2ebc47bbb9568e6d2c1f477fcb3b47cd0e70af7cc4996a2098339b1324f89421afd23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6d27ea4df5e00427f26270033145173dccae119b46efca0fe38c7242406629d0248ac409c91c263e0f576671bee7d8add130616d375a88bfb516f765ce7b68d8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b2ecd5eda6c33f22210e5cf8b002ec008653464d25173e05a863387319248babcd24e75ff99021229372f2b558e007285c2cc514209f068151fc863f3f9b21f9"} build_url:https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010848 build_url: 'https://queue.taskcluster.net/v1/task/ZKWzqfMVSMiGsesLPpxpAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6b844fa27f06955218f6873015c08d3967089ff504b9f05df2b3b6a81ba50c8d67d2e5715f315bb50eca59d63092bbd450d9370023c3b0d0236277e804c9c83b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/ed2a3f8760892f61df8998252a35a4381dc61dd700e88b49bd2927f863a2ebc47bbb9568e6d2c1f477fcb3b47cd0e70af7cc4996a2098339b1324f89421afd23", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/6d27ea4df5e00427f26270033145173dccae119b46efca0fe38c7242406629d0248ac409c91c263e0f576671bee7d8add130616d375a88bfb516f765ce7b68d8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/b2ecd5eda6c33f22210e5cf8b002ec008653464d25173e05a863387319248babcd24e75ff99021229372f2b558e007285c2cc514209f068151fc863f3f9b21f9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-18 23:13:32.979320) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 23:13:32.979732) ========= 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.PT3NkkqzUs/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.96K9bhmIlw/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.005224 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-18 23:13:33.029809) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-18 23:13:33.030577) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-18 23:13:33.031236) ========= ========= Total master_lag: 0.24 =========