builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0332 starttime: 1487091964.57 results: success (0) buildid: 20170214071445 builduid: afa550ad7f884889b6547a4e83396ba9 revision: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-14 09:06:04.574053) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-14 09:06:04.574479) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-14 09:06:04.574762) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-14 09:06:04.599860) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 09:06:04.600137) ========= 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.fJCrKfekoq/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.p6ECmNrNW2/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-14 09:06:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.8M=0.001s 2017-02-14 09:06:04 (17.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.222339 ========= master_lag: 0.71 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 09:06:05.535140) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 09:06:05.535450) ========= 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.fJCrKfekoq/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.p6ECmNrNW2/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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.025883 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 09:06:05.584675) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-14 09:06:05.585012) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fJCrKfekoq/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.p6ECmNrNW2/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-14 09:06:05,690 truncating revision to first 12 chars 2017-02-14 09:06:05,690 Setting DEBUG logging. 2017-02-14 09:06:05,690 attempt 1/10 2017-02-14 09:06:05,690 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fc9f6f7e8e86?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-14 09:06:07,472 unpacking tar archive at: mozilla-central-fc9f6f7e8e86/testing/mozharness/ program finished with exit code 0 elapsedTime=2.057666 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-02-14 09:06:07.668342) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-14 09:06:07.669113) ========= script_repo_revision: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-14 09:06:07.670123) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-14 09:06:07.670757) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-14 09:06:07.774914) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-02-14 09:06:07.775386) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fJCrKfekoq/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.p6ECmNrNW2/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:06:07 INFO - MultiFileLogger online at 20170214 09:06:07 in /builds/slave/test 09:06:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:06:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:06:07 INFO - {'append_to_log': False, 09:06:07 INFO - 'base_work_dir': '/builds/slave/test', 09:06:07 INFO - 'blob_upload_branch': 'Firefox', 09:06:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:06:07 INFO - 'branch': 'Firefox', 09:06:07 INFO - 'buildbot_json_path': 'buildprops.json', 09:06:07 INFO - 'config_files': ('talos/mac_config.py',), 09:06:07 INFO - 'default_actions': ('clobber', 09:06:07 INFO - 'read-buildbot-config', 09:06:07 INFO - 'download-and-extract', 09:06:07 INFO - 'populate-webroot', 09:06:07 INFO - 'create-virtualenv', 09:06:07 INFO - 'install', 09:06:07 INFO - 'run-tests'), 09:06:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:06:07 INFO - 'download_minidump_stackwalk': True, 09:06:07 INFO - 'download_symbols': 'ondemand', 09:06:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:06:07 INFO - 'tooltool.py': '/tools/tooltool.py', 09:06:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:06:07 INFO - '/tools/misc-python/virtualenv.py')}, 09:06:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:06:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:06:07 INFO - 'installer_path': 'installer.exe', 09:06:07 INFO - 'log_level': 'info', 09:06:07 INFO - 'log_name': 'talos', 09:06:07 INFO - 'log_to_console': True, 09:06:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:06:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:06:07 INFO - 'opt_config_files': (), 09:06:07 INFO - 'pip_index': False, 09:06:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:06:07 INFO - 'cmd': ('bash', 09:06:07 INFO - '-c', 09:06:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:06:07 INFO - 'enabled': True, 09:06:07 INFO - 'halt_on_failure': False, 09:06:07 INFO - 'name': 'check_screen_resolution'},), 09:06:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:06:07 INFO - 'cmd': ('bash', 09:06:07 INFO - '-c', 09:06:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:06:07 INFO - 'enabled': True, 09:06:07 INFO - 'halt_on_failure': False, 09:06:07 INFO - 'name': 'check_screen_resolution'},), 09:06:07 INFO - 'run_cmd_checks_enabled': True, 09:06:07 INFO - 'sps_profile': False, 09:06:07 INFO - 'sps_profile_interval': 0, 09:06:07 INFO - 'suite': 'dromaeojs', 09:06:07 INFO - 'system_bits': '32', 09:06:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:06:07 INFO - 'title': 't-yosemite-r7-0332', 09:06:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:06:07 INFO - 'use_talos_json': True, 09:06:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:06:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:06:07 INFO - 'work_dir': 'build'} 09:06:07 INFO - [mozharness: 2017-02-14 17:06:07.990517Z] Running clobber step. 09:06:07 INFO - Running pre-action listener: _resource_record_pre_action 09:06:07 INFO - Running main action method: clobber 09:06:07 INFO - rmtree: /builds/slave/test/build 09:06:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:06:10 INFO - Running post-action listener: _resource_record_post_action 09:06:10 INFO - [mozharness: 2017-02-14 17:06:10.371086Z] Finished clobber step (success) 09:06:10 INFO - [mozharness: 2017-02-14 17:06:10.371216Z] Running read-buildbot-config step. 09:06:10 INFO - Running pre-action listener: _resource_record_pre_action 09:06:10 INFO - Running main action method: read_buildbot_config 09:06:10 INFO - Using buildbot properties: 09:06:10 INFO - { 09:06:10 INFO - "project": "", 09:06:10 INFO - "product": "firefox", 09:06:10 INFO - "script_repo_revision": "production", 09:06:10 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:06:10 INFO - "repository": "", 09:06:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:06:10 INFO - "buildid": "20170214071445", 09:06:10 INFO - "pgo_build": "False", 09:06:10 INFO - "basedir": "/builds/slave/test", 09:06:10 INFO - "buildnumber": 88, 09:06:10 INFO - "slavename": "t-yosemite-r7-0332", 09:06:10 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:06:10 INFO - "platform": "yosemite_r7", 09:06:10 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:06:10 INFO - "branch": "mozilla-central", 09:06:10 INFO - "repo_path": "mozilla-central", 09:06:10 INFO - "revision": "fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd", 09:06:10 INFO - "stage_platform": "macosx64", 09:06:10 INFO - "builduid": "afa550ad7f884889b6547a4e83396ba9", 09:06:10 INFO - "slavebuilddir": "test" 09:06:10 INFO - } 09:06:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 09:06:10 INFO - Running post-action listener: _resource_record_post_action 09:06:10 INFO - [mozharness: 2017-02-14 17:06:10.374574Z] Finished read-buildbot-config step (success) 09:06:10 INFO - [mozharness: 2017-02-14 17:06:10.374708Z] Running download-and-extract step. 09:06:10 INFO - Running pre-action listener: _resource_record_pre_action 09:06:10 INFO - Running main action method: download_and_extract 09:06:10 INFO - mkdir: /builds/slave/test/build/tests 09:06:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:10 INFO - trying https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 09:06:10 INFO - Downloading https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 09:06:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 09:06:11 INFO - Downloaded 1199 bytes. 09:06:11 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 09:06:11 INFO - Using the following test package requirements: 09:06:11 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 09:06:11 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:06:11 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 09:06:11 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:06:11 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 09:06:11 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:06:11 INFO - u'jsshell-mac.zip'], 09:06:11 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:06:11 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 09:06:11 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 09:06:11 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:06:11 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 09:06:11 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:06:11 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 09:06:11 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:06:11 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 09:06:11 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 09:06:11 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 09:06:11 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:06:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 09:06:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:06:11 INFO - Fetch https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 09:06:13 INFO - Expected file size: 51504348 09:06:13 INFO - Obtained file size: 51504348 09:06:28 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:06:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 09:06:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:06:28 INFO - Fetch https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 09:06:30 INFO - Expected file size: 51504348 09:06:30 INFO - Obtained file size: 51504348 09:06:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 09:06:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:06:45 INFO - Fetch https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 09:06:46 INFO - Expected file size: 14403647 09:06:46 INFO - Obtained file size: 14403647 09:06:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:46 INFO - trying https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:06:46 INFO - Downloading https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 09:06:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 09:06:48 INFO - Downloaded 70417704 bytes. 09:06:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:06:48 INFO - mkdir: /builds/slave/test/properties 09:06:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:06:48 INFO - Writing to file /builds/slave/test/properties/build_url 09:06:48 INFO - Contents: 09:06:48 INFO - build_url:https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 09:06:49 INFO - Running post-action listener: _resource_record_post_action 09:06:49 INFO - Running post-action listener: set_extra_try_arguments 09:06:49 INFO - [mozharness: 2017-02-14 17:06:49.584939Z] Finished download-and-extract step (success) 09:06:49 INFO - [mozharness: 2017-02-14 17:06:49.585090Z] Running populate-webroot step. 09:06:49 INFO - Running pre-action listener: _resource_record_pre_action 09:06:49 INFO - Running main action method: populate_webroot 09:06:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:06:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:06:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:06:49 INFO - '--tppagecycles', 09:06:49 INFO - '7'], 09:06:49 INFO - 'tests': ['tsvgm']}}, 09:06:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:06:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:06:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:06:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:06:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:06:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:06:49 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:06:49 INFO - 'talos_options': ['--disable-e10s'], 09:06:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:06:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:06:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:06:49 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:06:49 INFO - 'talos_options': ['--disable-e10s'], 09:06:49 INFO - 'tests': ['damp', 'tps']}, 09:06:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:06:49 INFO - 'tests': ['damp', 'tps']}, 09:06:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:06:49 INFO - 'tests': ['dromaeo_dom']}, 09:06:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:06:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:06:49 INFO - 'tests': ['basic_compositor_video']}, 09:06:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:06:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:06:49 INFO - 'tests': ['a11yr', 09:06:49 INFO - 'ts_paint', 09:06:49 INFO - 'tpaint', 09:06:49 INFO - 'sessionrestore', 09:06:49 INFO - 'sessionrestore_no_auto_restore', 09:06:49 INFO - 'tabpaint']}, 09:06:49 INFO - 'other-e10s': {'tests': ['a11yr', 09:06:49 INFO - 'ts_paint', 09:06:49 INFO - 'tpaint', 09:06:49 INFO - 'sessionrestore', 09:06:49 INFO - 'sessionrestore_no_auto_restore', 09:06:49 INFO - 'tabpaint']}, 09:06:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:06:49 INFO - 'ts_paint', 09:06:49 INFO - 'tpaint', 09:06:49 INFO - 'sessionrestore', 09:06:49 INFO - 'sessionrestore_no_auto_restore', 09:06:49 INFO - 'tabpaint']}, 09:06:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:06:49 INFO - 'ts_paint', 09:06:49 INFO - 'tpaint', 09:06:49 INFO - 'sessionrestore', 09:06:49 INFO - 'sessionrestore_no_auto_restore', 09:06:49 INFO - 'tabpaint']}, 09:06:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:06:49 INFO - 'tests': ['a11yr', 09:06:49 INFO - 'ts_paint', 09:06:49 INFO - 'tpaint', 09:06:49 INFO - 'sessionrestore', 09:06:49 INFO - 'sessionrestore_no_auto_restore', 09:06:49 INFO - 'tabpaint']}, 09:06:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:06:49 INFO - 'tests': ['a11yr', 09:06:49 INFO - 'ts_paint', 09:06:49 INFO - 'tpaint', 09:06:49 INFO - 'sessionrestore', 09:06:49 INFO - 'sessionrestore_no_auto_restore', 09:06:49 INFO - 'tabpaint']}, 09:06:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:06:49 INFO - 'tests': ['tsvgx', 09:06:49 INFO - 'tsvgr_opacity', 09:06:49 INFO - 'tart', 09:06:49 INFO - 'tscrollx', 09:06:49 INFO - 'cart', 09:06:49 INFO - 'tsvg_static']}, 09:06:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:06:49 INFO - 'tsvgr_opacity', 09:06:49 INFO - 'tart', 09:06:49 INFO - 'tscrollx', 09:06:49 INFO - 'cart', 09:06:49 INFO - 'tsvg_static']}, 09:06:49 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:06:49 INFO - 'talos_options': ['--disable-e10s'], 09:06:49 INFO - 'tests': ['tp5o']}, 09:06:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:06:49 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:06:49 INFO - 'talos_options': ['--disable-e10s', 09:06:49 INFO - '--xperf_path', 09:06:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:06:49 INFO - 'tests': ['tp5n']}, 09:06:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:06:49 INFO - 'talos_options': ['--xperf_path', 09:06:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:06:49 INFO - 'tests': ['tp5n']}}, 09:06:49 INFO - 'talos.zip': {'path': '', 09:06:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:06:49 INFO - Running post-action listener: _resource_record_post_action 09:06:49 INFO - [mozharness: 2017-02-14 17:06:49.603544Z] Finished populate-webroot step (success) 09:06:49 INFO - [mozharness: 2017-02-14 17:06:49.603651Z] Running create-virtualenv step. 09:06:49 INFO - Running pre-action listener: _resource_record_pre_action 09:06:49 INFO - Running main action method: create_virtualenv 09:06:49 INFO - Creating virtualenv /builds/slave/test/build/venv 09:06:49 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:06:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:06:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:06:49 INFO - Using real prefix '/tools/python27' 09:06:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:06:50 INFO - Installing distribute.............................................................................................................................................................................................done. 09:06:53 INFO - Installing pip.................done. 09:06:53 INFO - Return code: 0 09:06:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:06:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:06:53 INFO - Reading from file tmpfile_stdout 09:06:53 INFO - Output received: 09:06:53 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:06:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:53 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 0x103c07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a61dbde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e5d30>, '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 0x10390cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039dabe8>, '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.fJCrKfekoq/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.p6ECmNrNW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:06:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:06:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:06:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJCrKfekoq/Render', 09:06:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:53 INFO - 'HOME': '/Users/cltbld', 09:06:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:53 INFO - 'LOGNAME': 'cltbld', 09:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:53 INFO - 'MOZ_NO_REMOTE': '1', 09:06:53 INFO - 'NO_EM_RESTART': '1', 09:06:53 INFO - 'PAGER': '/bin/cat', 09:06:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:53 INFO - 'PWD': '/builds/slave/test', 09:06:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:53 INFO - 'SHELL': '/bin/bash', 09:06:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6ECmNrNW2/Listeners', 09:06:53 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:06:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:53 INFO - 'USER': 'cltbld', 09:06:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:53 INFO - 'XPC_FLAGS': '0x0', 09:06:53 INFO - 'XPC_SERVICE_NAME': '0', 09:06:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:53 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:06:53 INFO - Cleaning up... 09:06:53 INFO - Return code: 0 09:06:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:53 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 0x103c07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a61dbde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e5d30>, '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 0x10390cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039dabe8>, '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.fJCrKfekoq/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.p6ECmNrNW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:06:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:06:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:06:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJCrKfekoq/Render', 09:06:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:53 INFO - 'HOME': '/Users/cltbld', 09:06:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:53 INFO - 'LOGNAME': 'cltbld', 09:06:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:53 INFO - 'MOZ_NO_REMOTE': '1', 09:06:53 INFO - 'NO_EM_RESTART': '1', 09:06:53 INFO - 'PAGER': '/bin/cat', 09:06:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:53 INFO - 'PWD': '/builds/slave/test', 09:06:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:53 INFO - 'SHELL': '/bin/bash', 09:06:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6ECmNrNW2/Listeners', 09:06:53 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:06:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:53 INFO - 'USER': 'cltbld', 09:06:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:53 INFO - 'XPC_FLAGS': '0x0', 09:06:53 INFO - 'XPC_SERVICE_NAME': '0', 09:06:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:54 INFO - Downloading/unpacking psutil>=3.1.1 09:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:06:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:06:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:06:57 INFO - Installing collected packages: psutil 09:06:57 INFO - Running setup.py install for psutil 09:06:57 INFO - building 'psutil._psutil_osx' extension 09:06:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:06: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:06: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:06:58 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:06:58 INFO - building 'psutil._psutil_posix' extension 09:06:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:06:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:06:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:06:58 INFO - ^ 09:06:58 INFO - 1 warning generated. 09:06:58 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:06:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:06:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:06:58 INFO - Successfully installed psutil 09:06:58 INFO - Cleaning up... 09:06:58 INFO - Return code: 0 09:06:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:06:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:58 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 0x103c07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a61dbde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e5d30>, '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 0x10390cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039dabe8>, '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.fJCrKfekoq/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.p6ECmNrNW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:06:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:06:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:06:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJCrKfekoq/Render', 09:06:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:58 INFO - 'HOME': '/Users/cltbld', 09:06:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:58 INFO - 'LOGNAME': 'cltbld', 09:06:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:58 INFO - 'MOZ_NO_REMOTE': '1', 09:06:58 INFO - 'NO_EM_RESTART': '1', 09:06:58 INFO - 'PAGER': '/bin/cat', 09:06:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:58 INFO - 'PWD': '/builds/slave/test', 09:06:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:58 INFO - 'SHELL': '/bin/bash', 09:06:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6ECmNrNW2/Listeners', 09:06:58 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:06:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:58 INFO - 'USER': 'cltbld', 09:06:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:58 INFO - 'XPC_FLAGS': '0x0', 09:06:58 INFO - 'XPC_SERVICE_NAME': '0', 09:06:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:06:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:07:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:07:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:07:01 INFO - Installing collected packages: mozsystemmonitor 09:07:01 INFO - Running setup.py install for mozsystemmonitor 09:07:02 INFO - Successfully installed mozsystemmonitor 09:07:02 INFO - Cleaning up... 09:07:02 INFO - Return code: 0 09:07:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:02 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 0x103c07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a61dbde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e5d30>, '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 0x10390cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039dabe8>, '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.fJCrKfekoq/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.p6ECmNrNW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:07:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:07:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:07:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJCrKfekoq/Render', 09:07:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:02 INFO - 'HOME': '/Users/cltbld', 09:07:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:02 INFO - 'LOGNAME': 'cltbld', 09:07:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:02 INFO - 'MOZ_NO_REMOTE': '1', 09:07:02 INFO - 'NO_EM_RESTART': '1', 09:07:02 INFO - 'PAGER': '/bin/cat', 09:07:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:02 INFO - 'PWD': '/builds/slave/test', 09:07:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:02 INFO - 'SHELL': '/bin/bash', 09:07:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6ECmNrNW2/Listeners', 09:07:02 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:07:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:02 INFO - 'USER': 'cltbld', 09:07:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:02 INFO - 'XPC_FLAGS': '0x0', 09:07:02 INFO - 'XPC_SERVICE_NAME': '0', 09:07:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:03 INFO - Downloading/unpacking jsonschema==2.5.1 09:07:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:07:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:07:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:06 INFO - Downloading functools32-3.2.3-2.tar.gz 09:07:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:07:06 INFO - warning: no files found matching '*.txt' 09:07:06 INFO - no previously-included directories found matching 'build' 09:07:06 INFO - no previously-included directories found matching 'dist' 09:07:06 INFO - no previously-included directories found matching '.git*' 09:07:06 INFO - Installing collected packages: jsonschema, functools32 09:07:06 INFO - Running setup.py install for functools32 09:07:06 INFO - warning: no files found matching '*.txt' 09:07:06 INFO - no previously-included directories found matching 'build' 09:07:06 INFO - no previously-included directories found matching 'dist' 09:07:06 INFO - no previously-included directories found matching '.git*' 09:07:06 INFO - Successfully installed jsonschema functools32 09:07:06 INFO - Cleaning up... 09:07:06 INFO - Return code: 0 09:07:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:07:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:06 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 0x103c07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a61dbde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e5d30>, '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 0x10390cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039dabe8>, '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.fJCrKfekoq/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.p6ECmNrNW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:07:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:07:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:07:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJCrKfekoq/Render', 09:07:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:06 INFO - 'HOME': '/Users/cltbld', 09:07:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:06 INFO - 'LOGNAME': 'cltbld', 09:07:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:06 INFO - 'MOZ_NO_REMOTE': '1', 09:07:06 INFO - 'NO_EM_RESTART': '1', 09:07:06 INFO - 'PAGER': '/bin/cat', 09:07:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:06 INFO - 'PWD': '/builds/slave/test', 09:07:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:06 INFO - 'SHELL': '/bin/bash', 09:07:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6ECmNrNW2/Listeners', 09:07:06 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:07:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:06 INFO - 'USER': 'cltbld', 09:07:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:06 INFO - 'XPC_FLAGS': '0x0', 09:07:06 INFO - 'XPC_SERVICE_NAME': '0', 09:07:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:07:07 INFO - Cleaning up... 09:07:07 INFO - Return code: 0 09:07:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:07:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:07 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 0x103c07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a61dbde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e5d30>, '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 0x10390cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039dabe8>, '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.fJCrKfekoq/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.p6ECmNrNW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:07:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:07:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:07:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJCrKfekoq/Render', 09:07:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:07 INFO - 'HOME': '/Users/cltbld', 09:07:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:07 INFO - 'LOGNAME': 'cltbld', 09:07:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:07 INFO - 'MOZ_NO_REMOTE': '1', 09:07:07 INFO - 'NO_EM_RESTART': '1', 09:07:07 INFO - 'PAGER': '/bin/cat', 09:07:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:07 INFO - 'PWD': '/builds/slave/test', 09:07:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:07 INFO - 'SHELL': '/bin/bash', 09:07:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6ECmNrNW2/Listeners', 09:07:07 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:07:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:07 INFO - 'USER': 'cltbld', 09:07:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:07 INFO - 'XPC_FLAGS': '0x0', 09:07:07 INFO - 'XPC_SERVICE_NAME': '0', 09:07:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:07 INFO - Downloading/unpacking blobuploader==1.2.4 09:07: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 09:07: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 09:07: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 09:07: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 09:07: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 09:07: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 09:07:10 INFO - Downloading blobuploader-1.2.4.tar.gz 09:07:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:07:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:07:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:11 INFO - Downloading docopt-0.6.1.tar.gz 09:07:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:07:11 INFO - Installing collected packages: blobuploader, requests, docopt 09:07:11 INFO - Running setup.py install for blobuploader 09:07:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:07:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:07:11 INFO - Running setup.py install for requests 09:07:11 INFO - Running setup.py install for docopt 09:07:12 INFO - Successfully installed blobuploader requests docopt 09:07:12 INFO - Cleaning up... 09:07:12 INFO - Return code: 0 09:07:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:12 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 0x103c07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a61dbde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e5d30>, '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 0x10390cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039dabe8>, '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.fJCrKfekoq/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.p6ECmNrNW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:07:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:07:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:07:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJCrKfekoq/Render', 09:07:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:12 INFO - 'HOME': '/Users/cltbld', 09:07:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:12 INFO - 'LOGNAME': 'cltbld', 09:07:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:12 INFO - 'MOZ_NO_REMOTE': '1', 09:07:12 INFO - 'NO_EM_RESTART': '1', 09:07:12 INFO - 'PAGER': '/bin/cat', 09:07:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:12 INFO - 'PWD': '/builds/slave/test', 09:07:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:12 INFO - 'SHELL': '/bin/bash', 09:07:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6ECmNrNW2/Listeners', 09:07:12 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:07:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:12 INFO - 'USER': 'cltbld', 09:07:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:12 INFO - 'XPC_FLAGS': '0x0', 09:07:12 INFO - 'XPC_SERVICE_NAME': '0', 09:07:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:07:12 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-T_naM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:07:12 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-LSz6VL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:07:12 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-2hRflI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:07:12 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-uF2Evd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:07:12 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-cKjIJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:07:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:07:12 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-Kw5VUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:07:13 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-0QPclw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:07:13 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-dumEZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:07:13 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-LrHfha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:07:13 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-fqP1m1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:07:13 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-DI329B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:07:13 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-jOc0et-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:07:13 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-aEZB1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:07:13 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-KWbljp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:13 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-9BTEnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:07:13 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-x9DI9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:07:14 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-k37pxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:07:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:07:14 INFO - Running setup.py install for manifestparser 09:07:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:07:14 INFO - Running setup.py install for mozcrash 09:07:14 INFO - Running setup.py install for mozdebug 09:07:14 INFO - Running setup.py install for mozdevice 09:07:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:07:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:07:14 INFO - Running setup.py install for mozfile 09:07:14 INFO - Running setup.py install for mozhttpd 09:07:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:07:15 INFO - Running setup.py install for mozinfo 09:07:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:07:15 INFO - Running setup.py install for mozInstall 09:07:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:07:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:07:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:07:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:07:15 INFO - Running setup.py install for mozleak 09:07:15 INFO - Running setup.py install for mozlog 09:07:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:07:15 INFO - Running setup.py install for moznetwork 09:07:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:07:15 INFO - Running setup.py install for mozprocess 09:07:16 INFO - Running setup.py install for mozprofile 09:07:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:07:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:07:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:07:16 INFO - Running setup.py install for mozrunner 09:07:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:07:16 INFO - Running setup.py install for mozscreenshot 09:07:16 INFO - Running setup.py install for moztest 09:07:16 INFO - Running setup.py install for mozversion 09:07:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:07:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:07:16 INFO - Cleaning up... 09:07:16 INFO - Return code: 0 09:07:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:16 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 0x103c07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a61dbde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e5d30>, '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 0x10390cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039dabe8>, '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.fJCrKfekoq/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.p6ECmNrNW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:07:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:07:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:07:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJCrKfekoq/Render', 09:07:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:16 INFO - 'HOME': '/Users/cltbld', 09:07:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:16 INFO - 'LOGNAME': 'cltbld', 09:07:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:16 INFO - 'MOZ_NO_REMOTE': '1', 09:07:16 INFO - 'NO_EM_RESTART': '1', 09:07:16 INFO - 'PAGER': '/bin/cat', 09:07:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:16 INFO - 'PWD': '/builds/slave/test', 09:07:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:16 INFO - 'SHELL': '/bin/bash', 09:07:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6ECmNrNW2/Listeners', 09:07:16 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:07:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:16 INFO - 'USER': 'cltbld', 09:07:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:16 INFO - 'XPC_FLAGS': '0x0', 09:07:16 INFO - 'XPC_SERVICE_NAME': '0', 09:07:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:07:17 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-vc1fYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:07:17 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-ZyaTIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:07:17 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-8g4DBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:07:17 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-9fG5_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:07:17 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-syeE0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:07:17 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-B9UoZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:07:17 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-KHca_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:07:17 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-gqRR71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:07:17 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-qNQ7eD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:07:18 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-L4JiS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:07:18 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-oKa7GN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:07:18 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-LVx7ii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:07:18 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-TeY6DN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:07:18 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-Y1bUf8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:18 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-FYXq2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:07:18 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-XRdAw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:07:18 INFO - Running setup.py (path:/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/pip-fb5r8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:07:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:07:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:21 INFO - Downloading blessings-1.6.tar.gz 09:07:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:07:21 INFO - Installing collected packages: blessings 09:07:21 INFO - Running setup.py install for blessings 09:07:21 INFO - Successfully installed blessings 09:07:21 INFO - Cleaning up... 09:07:22 INFO - Return code: 0 09:07:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:07:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:07:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:07:22 INFO - Reading from file tmpfile_stdout 09:07:22 INFO - Current package versions: 09:07:22 INFO - blessings == 1.6 09:07:22 INFO - blobuploader == 1.2.4 09:07:22 INFO - docopt == 0.6.1 09:07:22 INFO - functools32 == 3.2.3-2 09:07:22 INFO - jsonschema == 2.5.1 09:07:22 INFO - manifestparser == 1.1 09:07:22 INFO - mozInstall == 1.12 09:07:22 INFO - mozcrash == 1.0 09:07:22 INFO - mozdebug == 0.1 09:07:22 INFO - mozdevice == 0.49 09:07:22 INFO - mozfile == 1.2 09:07:22 INFO - mozhttpd == 0.7 09:07:22 INFO - mozinfo == 0.9 09:07:22 INFO - mozleak == 0.1 09:07:22 INFO - mozlog == 3.4 09:07:22 INFO - moznetwork == 0.27 09:07:22 INFO - mozprocess == 0.24 09:07:22 INFO - mozprofile == 0.28 09:07:22 INFO - mozrunner == 6.13 09:07:22 INFO - mozscreenshot == 0.1 09:07:22 INFO - mozsystemmonitor == 0.3 09:07:22 INFO - moztest == 0.8 09:07:22 INFO - mozversion == 1.4 09:07:22 INFO - psutil == 3.1.1 09:07:22 INFO - requests == 1.2.3 09:07:22 INFO - wsgiref == 0.1.2 09:07:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:22 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 0x103c07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a61dbde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e5d30>, '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 0x10390cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039dabe8>, '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.fJCrKfekoq/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.p6ECmNrNW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:07:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:07:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:07:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJCrKfekoq/Render', 09:07:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:22 INFO - 'HOME': '/Users/cltbld', 09:07:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:22 INFO - 'LOGNAME': 'cltbld', 09:07:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:22 INFO - 'MOZ_NO_REMOTE': '1', 09:07:22 INFO - 'NO_EM_RESTART': '1', 09:07:22 INFO - 'PAGER': '/bin/cat', 09:07:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:22 INFO - 'PWD': '/builds/slave/test', 09:07:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:22 INFO - 'SHELL': '/bin/bash', 09:07:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6ECmNrNW2/Listeners', 09:07:22 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:07:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:22 INFO - 'USER': 'cltbld', 09:07:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:22 INFO - 'XPC_FLAGS': '0x0', 09:07:22 INFO - 'XPC_SERVICE_NAME': '0', 09:07:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:07:22 INFO - Cleaning up... 09:07:22 INFO - Return code: 0 09:07:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:22 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 0x103c07b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c0a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a61dbde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039e5d30>, '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 0x10390cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039dabe8>, '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.fJCrKfekoq/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.p6ECmNrNW2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:07:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:07:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:07:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJCrKfekoq/Render', 09:07:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:22 INFO - 'HOME': '/Users/cltbld', 09:07:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:22 INFO - 'LOGNAME': 'cltbld', 09:07:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:22 INFO - 'MOZ_NO_REMOTE': '1', 09:07:22 INFO - 'NO_EM_RESTART': '1', 09:07:22 INFO - 'PAGER': '/bin/cat', 09:07:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:22 INFO - 'PWD': '/builds/slave/test', 09:07:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:22 INFO - 'SHELL': '/bin/bash', 09:07:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6ECmNrNW2/Listeners', 09:07:22 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:07:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:22 INFO - 'USER': 'cltbld', 09:07:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:22 INFO - 'XPC_FLAGS': '0x0', 09:07:22 INFO - 'XPC_SERVICE_NAME': '0', 09:07:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:07:22 INFO - Cleaning up... 09:07:22 INFO - Return code: 0 09:07:22 INFO - Running post-action listener: _resource_record_post_action 09:07:22 INFO - Running post-action listener: _start_resource_monitoring 09:07:22 INFO - Starting resource monitoring. 09:07:22 INFO - [mozharness: 2017-02-14 17:07:22.857200Z] Finished create-virtualenv step (success) 09:07:22 INFO - [mozharness: 2017-02-14 17:07:22.858192Z] Running install step. 09:07:22 INFO - Running pre-action listener: _resource_record_pre_action 09:07:22 INFO - Running main action method: install 09:07:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:07:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:07:23 INFO - Reading from file tmpfile_stdout 09:07:23 INFO - Detecting whether we're running mozinstall >=1.0... 09:07:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:07:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:07:23 INFO - Reading from file tmpfile_stdout 09:07:23 INFO - Output received: 09:07:23 INFO - Usage: mozinstall [options] installer 09:07:23 INFO - Options: 09:07:23 INFO - -h, --help show this help message and exit 09:07:23 INFO - -d DEST, --destination=DEST 09:07:23 INFO - Directory to install application into. [default: 09:07:23 INFO - "/builds/slave/test"] 09:07:23 INFO - --app=APP Application being installed. [default: firefox] 09:07:23 INFO - mkdir: /builds/slave/test/build/application 09:07:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:07:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:07:45 INFO - Reading from file tmpfile_stdout 09:07:45 INFO - Output received: 09:07:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:07:45 INFO - Running post-action listener: _resource_record_post_action 09:07:45 INFO - [mozharness: 2017-02-14 17:07:45.697228Z] Finished install step (success) 09:07:45 INFO - [mozharness: 2017-02-14 17:07:45.697406Z] Running run-tests step. 09:07:45 INFO - Running pre-action listener: _resource_record_pre_action 09:07:45 INFO - Running main action method: run_tests 09:07:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:07:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:07:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:07:45 INFO - 2017-02-14 09:07:45.711 screenresolution[1622:7621] starting screenresolution argv=screenresolution get 09:07:45 INFO - 2017-02-14 09:07:45.713 screenresolution[1622:7621] Display 0: 1600x1200x32@60 09:07:45 INFO - 2017-02-14 09:07:45.719 screenresolution[1623:7624] starting screenresolution argv=screenresolution list 09:07:45 INFO - Available Modes on Display 0 09:07:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:07:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:07:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:07:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:07:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:07:45 INFO - Intel Iris: 09:07:45 INFO - Chipset Model: Intel Iris 09:07:45 INFO - Type: GPU 09:07:45 INFO - Bus: Built-In 09:07:45 INFO - VRAM (Dynamic, Max): 1536 MB 09:07:45 INFO - Vendor: Intel (0x8086) 09:07:45 INFO - Device ID: 0x0a2e 09:07:45 INFO - Revision ID: 0x0009 09:07:45 INFO - Displays: 09:07:45 INFO - Display: 09:07:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:07:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:07:45 INFO - Display Serial Number: 1600x1200 60 09:07:45 INFO - Main Display: Yes 09:07:45 INFO - Mirror: Off 09:07:45 INFO - Online: Yes 09:07:45 INFO - Rotation: Supported 09:07:45 INFO - Return code: 0 09:07:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:07:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:07:45 INFO - Python 2.7.3 09:07:45 INFO - Return code: 0 09:07:45 INFO - grabbing minidump binary from tooltool 09:07:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:45 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 0x1039e5d30>, '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 0x10390cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1039dabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:07:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:07:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:07:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:07:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:07:46 INFO - Return code: 0 09:07:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:07:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:07:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:07:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:07:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:07:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0332', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:07:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0332 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:07:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fJCrKfekoq/Render', 09:07:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:46 INFO - 'HOME': '/Users/cltbld', 09:07:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:46 INFO - 'LOGNAME': 'cltbld', 09:07:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:07:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:07:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:46 INFO - 'MOZ_NO_REMOTE': '1', 09:07:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:07:46 INFO - 'NO_EM_RESTART': '1', 09:07:46 INFO - 'PAGER': '/bin/cat', 09:07:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:46 INFO - 'PWD': '/builds/slave/test', 09:07:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:07:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:46 INFO - 'SHELL': '/bin/bash', 09:07:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6ECmNrNW2/Listeners', 09:07:46 INFO - 'TMPDIR': '/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/', 09:07:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:46 INFO - 'USER': 'cltbld', 09:07:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:46 INFO - 'XPC_FLAGS': '0x0', 09:07:46 INFO - 'XPC_SERVICE_NAME': '0', 09:07:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0332', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:07:46 INFO - mozversion application_buildid: 20170214071445 09:07:46 INFO - mozversion application_changeset: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd 09:07:46 INFO - mozversion application_display_name: Nightly 09:07:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:07:46 INFO - mozversion application_name: Firefox 09:07:46 INFO - mozversion application_remotingname: firefox 09:07:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:07:46 INFO - mozversion application_vendor: Mozilla 09:07:46 INFO - mozversion application_version: 54.0a1 09:07:46 INFO - mozversion platform_buildid: 20170214071445 09:07:46 INFO - mozversion platform_changeset: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd 09:07:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:07:46 INFO - mozversion platform_version: 54.0a1 09:07:46 INFO - using testdate: 1487092066 09:07:46 INFO - actual date: 1487092066 09:07:46 INFO - starting webserver on 'localhost:49240' 09:07:46 INFO - SUITE-START | Running 2 tests 09:07:46 INFO - TEST-START | dromaeo_css 09:07:46 INFO - operating with platform_type : mac_ 09:07:46 INFO - Initialising browser for dromaeo_css test... 09:07:46 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpmKqYlW/profile http://localhost:49240/getInfo.html) 09:07:48 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 09:07:48 INFO - PROCESS | 1633 | colorDepth:24 09:07:48 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 09:07:48 INFO - PROCESS | 1633 | __metrics 09:07:48 INFO - PROCESS | 1633 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:07:48 INFO - PROCESS | 1633 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:07:49 INFO - TEST-INFO | 1633: exit 0 09:07:49 INFO - Browser initialized. 09:07:49 INFO - Running cycle 1/1 for dromaeo_css test... 09:07:49 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmpmKqYlW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:08:01 INFO - PROCESS | 1635 | RSS: Main: 235991040 09:08:01 INFO - PROCESS | 1635 | 09:11:05 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 09:11:08 INFO - PROCESS | 1635 | RSS: Main: 501600256 09:11:08 INFO - PROCESS | 1635 | 09:12:51 INFO - PROCESS | 1635 | ************************* 09:12:51 INFO - PROCESS | 1635 | A coding exception was thrown and uncaught in a Task. 09:12:51 INFO - PROCESS | 1635 | 09:12:51 ERROR - PROCESS | 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:12:51 INFO - PROCESS | 1635 | Full stack: 09:12:51 INFO - PROCESS | 1635 | ************************* 09:14:11 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 09:14:12 INFO - PROCESS | 1635 | RSS: Main: 382296064 09:14:12 INFO - PROCESS | 1635 | 09:17:14 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 09:17:15 INFO - PROCESS | 1635 | RSS: Main: 355209216 09:17:15 INFO - PROCESS | 1635 | 09:20:17 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 09:20:18 INFO - PROCESS | 1635 | RSS: Main: 342433792 09:20:18 INFO - PROCESS | 1635 | 09:23:21 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 09:23:22 INFO - PROCESS | 1635 | RSS: Main: 334692352 09:23:22 INFO - PROCESS | 1635 | 09:26:25 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 09:26:26 INFO - PROCESS | 1635 | RSS: Main: 491581440 09:26:26 INFO - PROCESS | 1635 | 09:26:26 INFO - PROCESS | 1635 | __start_tp_report 09:26:26 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 09:26:26 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 09:26:26 INFO - PROCESS | 1635 | |i|pagename|runs| 09:26:26 INFO - PROCESS | 1635 | |0;dojo.html;3124.1877111950885;3190.9469874941265;3324.16895776056;3329.4518049107173;3363.058343663775;5421.403645598981;5541.33503979522;5659.490645841874;5698.173764804106;5711.257536520252;5537.446255374461;5614.803481878135;5661.943380566193;5668.801591944282;5674.120511320752;4517.6672881500035;5069.898602027957;5070.416902056256;5147.073526765181;5153.587712982971;5220.190870415092;5227.73861306935;5229.346331708536;5252.868678283055;5262.684238945675;4997.6501644884875;5018.447970723218;5019.121653710623;5204.245384419276;5239.21411788231;5090.058339207221;5108.2593024011685;5134.973005398935;5146.742662866879;5230.607704422184;5100.515451032146;5108.6168537359845;5111.642185047048;5144.485551444863;5200.323957885451;5488.451154884519;5626.718664066781;5631.2960879890015;5642.4075472075665;5644.814588936329;6053.909191362133;6127.9837278401355;6129.662868542228;6179.752809887599;6196.504279657616;6220.782272620482;6261.78083767066;6267.091271765614;6271.153394291803;6282.874342513124;5773.278340207474;5785.450382213683;5786.710664466844;5800.4489573490455;5827.388124246978;6125.907024958145;6166.568340216142;6187.690615469299;6189.690515474208;6240.032794916795;5864.853378665567;5931.881362372815;5932.644041357532;5939.673317967553;5945.583809133319;6112.847178820476;6130.325664176937;6130.6628135452975;6134.2332208473945;6139.10982907476;5962.731677074542;6030.788922387783;6052.969735151296;6054.485368743618;6058.727357268934;5595.748191331319;6087.452129308295;6094.421030002142;6097;6107;4835.081334546425;4861.756912154378;4867.3915760529935;4870.14772414826;4875.414950205998;4335.891602709958;4345.978695006671;4346.500152482505;4357.411749413852;4436.334549817553;5729.7708091676695;5739.139129130664;5766.135079738073;5768.3654797972185;5774.942250577525;5871.530277577784;5916.319623243296;5919.526437884959;6297.6536290503545;6305.432511074026;5670.716464176775;5717.371089180187;5738.913916291259;5761.423857614289;5795.7971470999155;4858.659893807399;5412.5940554458575;5436.836894893159;5438.91841622376;5439.211314359396;6138.324784273726;6195;6264.123022776815;6273.96863015682;6285.402886725754;5070.8225212117395;5380.542653874464;6054.9091763624465;6108.038540470994;6144.53915956314;6225.128482012612;6248.968755156195;6252.280987686474;6255;6258.030005349178;6091.664958427329;6123.8162855114415;6133.815985520441;6141.723622436912;6149.7847575334645;5966.134910438049;6194.566380353332;6245.937540624535;6248.781292654917;6249.968750156402;5720.256366672306;5772.19189313488;5935.0207215808905;5967.164596956516;6000.819975400745;5676.262085928803;5781.248437703071;5848.6783226921225;5849.268841394825;5864.765409383747;5640.266765320482;5686.118651609007;5691.430856914276;5693.032184528723;5714.05718056516;5825.970870145622;5832.183494310715;5832.533397328119;5835.212246346859;5846.093855452412;5677.148427735872;5695.743691533974;5699.601027928005;5701.771929122954;5705.515031222392 09:26:26 INFO - PROCESS | 1635 | |1;ext.html;468.15498026062966;492.7093015121078;501.39331408995116;501.566145284329;516.6280278199695;36458.635413645854;37279.81360093199;37386.62613373865;38997;39051.80474097626;37735.62264377355;38200;38210.4268435973;38315.042123946914;38488.23023539528;7292.4530660200435;8956.641734330635;9171.358004939624;9171.816563668723;9173.724788256362;7259.927400726005;10781.892181078207;10976.9692606868;10989.780204395907;11108.722281942975;10952.835707464394;10984.450777461136;10993.725156871062;11009.174311926598;11025.173112016591;7192.820179495502;7384.2246564111065;10900.836487452694;10985.835212471779;10998.945005275002;10499.107575856062;10762.677119686421;10797.784044319149;10911.290766100177;10920.017198452177;36481;36803.711870084684;36804.0798980025;36871.262574748354;36933.44599830978;3751.249750050001;3816.217675376541;3821.751586146919;3822.140018495844;3896.59085795993;27649.585256221173;28528.429431411463;28669.56995645046;28778.856105719547;28836.711632883824;2720.2519307190482;3044.528098144791;3060.6021217241614;3133.8119712817074;3175.682431756829;297272.5136374326;297482;297735;297790.02209978056;298464.5076774624;49873.750631247334;50086.749566251936;50150.498495015316;50202.99999999927;50220;53786;54078.046976142134;54167.72916135394;54275.18587221195;54439;13162.802557961639;13291.93354033243;13344.132631378929;13345.132566383154;13350.799738003938;10276.486175691185;10310.587576496857;10341.948290258651;10343.482825858677;10352.948235258775;12277.570285039981;14028.929855350862;14137.5051873186;14183.64540886486;14201.857981420053;5986.431289027535;6046.123312119718;6075;6076.3316035236085;6088.299845517734;807.6244546285888;823.1315961660385;841.7432683031611;843.6498852976069;860.2300940658076;27057;27239.86380068087;27321;27816;28106.29734256619;995.4773743784571;1033.684726158529;1034.7479549744742;1037.5953378182514;1042.1610603464133;13240.668983275495;13850.515231967034;13949.232792196324;14082.366293516816;14348.784768228485;518672;520546;524042.0766338684;524393;526142;51791.70520737012;51962;52003.73998129985;52122.47877521252;52242.47757522376;1298.7402519496138;1357.1178733823094;1362.3937347880296;1363.2093385836192;1368.9452421903013;25362.492750144524;26833.736279556757;27185.32036699098;27627.58558621582;27742.167734967985;43754.90612734707;44108;44280.11439771251;44310;44327.55672443234;450426;454858;455783;457069;457457;34084.659153408145;38879.41680874732;40026.19489199578;40191.55498457802;40255.798721006206;42030;42070;43800.90497737582;43861.56138438575;45345.31982020338;34854.30291394208;35937.28125437424;36517.45223821592;36983.63016369802;37348.81325593355;27085.781139849147;35537.28925421425;36400.90797276086;36698.63301367059;36903;35654.64345356617;36085.27829443344;36659.26681466409;36933.81533092317;37194.62805371912;30588.694113058584;31483.52774708427;32197;32419.513707294882;32523;19466.513337166685;23670.644940325565;23817.629118644425;24258.87870560636;24366.390840229134 09:26:26 INFO - PROCESS | 1635 | |2;jquery.html;355.6532380928597;390.8182695046805;392.50740320897273;400.959904009599;421.3658444041717;13595.52415665452;15412.768808467868;19899.801001989978;20181;20234.69647955282;21736.782632173672;22075.33773986779;22079.44801379966;22140.8892955535;22141.335759927188;5565.387807341189;5623.578231632634;5705.4579814917515;5885.11723241515;5887.67617781024;5636.436356364372;5756.424357564231;5799.565032622549;5803.680797556132;5808.3030036395485;5494.395616482184;5695.174199741035;5737;5744.79893203738;5773.711314434283;5660;5695.746935674187;5717.9987004548075;5724.8855022899725;5733.226014488033;4788.784504697297;4956.677815941988;5019.347484826949;5042.218456139304;5051.343325367716;25417.491650167078;25769.61345579818;25799.742002579922;25880.87059564709;26127.216183514523;2334.4514039200776;2540.720520742717;2573.9099131530493;2655;2744.300203448108;5916.852078698024;6456.192975878015;9384.765380865465;9691.418514889128;10104.89702700139;1745.3454954392103;1756.8017657498217;1795.9640807183914;1900.0594705620667;1905.199816077251;102149;108591;112920;114810.42594787056;116389;10133.949330253301;10625.893741062646;10922.836157457645;11346.94326528362;11374.31754094757;15298.617534561503;15309.770353444706;15315.46395876139;15319.540413787605;15334;7585.393168546503;7761.534307941542;7842.098158711707;7862.72480463181;7879.290863822283;7177.820554486075;7208.207097219301;7213.350798428167;7344.375728063068;7351.926480735124;3893.4886997886206;4673.158831410311;4675.638802404832;4698.285125929011;4757.16749568831;1730.7144321186863;1945.6497830390665;1980.4256765537832;1987.8509111816381;1997.6803711406396;903.7333986473875;986.541258314887;987.2694206287439;993.1657407777501;1029.799189158107;17766;18216.908915455337;21360;21363.931803409767;21583;986.5856340337076;1003.2325271167563;1027.8304079826894;1028.83024300991;1039.4750650921237;9356.578953947088;10479.963461614801;10722.195835312383;10814.405207713651;10971.670849874517;603388;607202;611453;612677;626048.0508703332;86266.56866715626;100009.49995249977;110292;134867;138045.55929356592;892.8169725206317;961.7162936933621;980.7695191629962;993.8062077894886;996.3125443444145;19115.426537203904;19791.40625781229;19936.70094948548;19986.60026799427;20014.49963750918;44480.11039779251;46905.765471172424;52460.42618721444;53354;53854.461455384946;76578.61710691411;76994;78208.60895695485;78234;78874.6056269715;8097.2307630774985;8151;8309.709160179365;8335.541545214828;8391.874121888299;11570;12801.55194568223;12820.551280705131;13194.868051319749;13219;8641.913580864111;11349.716257093309;11358.204925655125;11365.261258018203;11473.541058357572;11102;11245.493952772309;11268.774624507629;11412.600558980395;11741;11447.885521144683;11588.130890183102;11798.587049453228;11812.586559470377;11815.881841181477;7228.096487939008;7356.926430735624;7505.924940750522;8037.8392432152195;8461.576921153795;8351.20663536963;10304.330218536072;11225.4387280634;11794;11842.407879605813 09:26:26 INFO - PROCESS | 1635 | |3;mootools.html;1257.6352857671272;1357.2263809628516;1439.8128243328365;1457.8118833150982;1475.2623688155918;8068.6772529098835;8213.425060245785;8459.87310190347;8466.619002144902;8481.279091277242;8259.29795967342;8435.662573497068;8523;8544.23101920827;8559.887214662102;1851.3890416162635;2201.581699477102;2233.62028455163;2240.9775902241013;2259;1866.7199920011972;1897.6394485047797;1972.7336809530673;2271.761465046171;2300.8849557522058;1829.0580351119183;1936.9225230990744;2218.955620887581;2240.7759224077545;2245.62947113726;2196.439907823503;2233.7096177496983;2238.373255488466;2249.044156233594;2253.5042290696183;2225.254539729193;2230.6170174491917;2258.525709600986;2262.400463877074;2270.1146552844402;8376.49741015541;8424.199701028392;8444.873326900102;8467.830643387098;8487.363447741382;4950.603951683856;5107.565856902131;5129.691928558231;5132.8460146195675;5133.076046311662;3195.6325022622464;3243.724283435911;3388.5594872666643;3431.3995050865997;3446.3796516627;1225.914186006971;1249.443997421148;1663.6007358234058;1718.3384397007117;1740.0951505217342;10620.946895265552;10874.293170943942;10876.6193183239;10876.836847447295;10896;4426.9778651106535;4534.0251845852645;4564.6348292136545;4633.791479383436;4673.906521869543;8765.737027889047;8820.955895220482;8821.823563528693;8822.426542274732;8843.336749743796;5097.388313402415;5109.897802043938;5111.105556527593;5112.9744351278005;5117.8464646060675;4557.5442455754155;4700.271457923959;4773.9761301193275;4774.164521208774;4792.448868380111;3372.7639065265685;3435.9484607730906;3460.8096554689732;3507.8947631571095;3515.2969406118877;994.8656931314325;1108.3682301088302;1113.4265853085667;1114.2200459678259;1136.9033632141195;648.7307767276621;651.726274964516;704.3479558489249;718.6442710858054;722.3101937649532;5368.8389348319615;5429.131338985822;5574.358948720868;5574.414686457863;5596.4815832875365;789.4749991255751;893.718478679214;949.7530642033121;964.5996911281879;1004.5630150884389;3334.5665063541583;3649.4160934250394;3755.2301778135416;3793.677537409296;3828.770273783582;9622.37454565451;9897.80204395908;9908.950455247677;9909.900900991042;9934.80130397388;9544.284178686627;9600.039996000343;9756;9853.802923941481;9892.55483503244;6401.391867772554;7400.703971841173;7597.164311925684;7602.391808655294;7612.885806712793;5325.640301178158;5377.300950876361;5460.672359658433;5481.890362192814;5516.862078447911;9014;9158.862617060882;9174;9182.770430739285;9196.770080747767;8734.301255899385;8993.595288211915;9200.033996430546;9267.304952128752;9274.026227246042;7558.924410756042;7670.117936437381;7699.307062364414;7765.41759368061;7826.060872695313;7605.6577454013595;7800.063992320957;7804.84390312202;7813.257740514642;7824.530528168328;6831.248562658203;6996.930030699831;7009.649517524205;7036.964815175891;7067.752628657972;6846.452283817183;6915.101036865377;6924.58452492852;6928.792136235921;6950.235474097946;6080.969595152173;6206.72069756853;6290.937090629218;6303.558750887394;6318.9052164216655;4867.415910090812;4871.951280487291;4929.5809856161195;4970.751462426936;5070.518300761421;4833.975830120827;4856.732879691651;4932.53140951609;4997.575206107522;5008.073506401327 09:26:26 INFO - PROCESS | 1635 | |4;prototype.html;349.13414731465963;352.50473085315133;362.06406439354276;366.39178962921557;366.6205477330963;5058.5953123750105;5180.430152683201;5181.507756763111;5269.5520880725135;5370.180655156045;4383.868483945486;4696.64775141864;4711.363965864607;5008.9230815374685;5114.156164232897;4070.633642972132;4087.7751723655188;4103.199876024177;4127.8968025799295;4151.720141088536;3247.3829972305302;4093.713440059197;4111.259973204822;4119.423280740699;4140.60664236897;3332.316875040623;4052.1432390164864;4065.272259289805;4069.3285607874664;4111.362738775495;4050.9594904050878;4071.9687509365813;4073.8574149904907;4078.5921407859123;4100.7334523255895;3613.8012409317475;3621.8410108765206;3622.8913132606062;3639.9272014559824;3657.9817100914593;4894.089086549445;4961.231009193545;5060.898782024376;5064.772085256172;5128.282040514331;4678.193084149517;4704.694194877322;4706.576408123286;4714.481407045222;4732.952670473285;5468.72656367184;5472.619410556381;5489.478499542537;5507.7246137693355;5560.833175004756;3572.464130380438;3932.7218653937584;3933.7639741615308;3994.840206391741;4006.318925782624;4786.856394308176;5441.455854414567;5519.806806761784;5535.612507124543;5777.851071166225;3138.6660669215494;3810.65704086628;3818.312703713358;3855.609522096262;4041.2725709372594;3617.529721136288;3963.7027222958395;4123.36087906375;4177.456930599003;4187.937180942288;4400.107953579992;4407.228734971432;4558.652294068557;4790.233562629963;4790.928136077961;4812.230043193073;5475.67145971243;5478.178273259043;5497.257870187554;5509.834704958857;1200.7572162811448;1203.4343858386544;1308.4177540994356;1320.518010926005;1337.936340609218;796.8884356190138;883.8232353529191;966.5553845231132;968.970930872075;977.1694060048901;4792.496787837227;4812.855614331575;4818.494058123846;4823.517648235148;4826.693714805369;4772.184411197411;5216.7913283469;5281.471852814765;5284.445133261029;5309.716831752431;4305.547917468621;4647.094691176601;4938.333325001079;4939.728314942641;4972.254161875748;2515.9496810063697;2784.805063645566;2811.2971757060736;2812.831230126158;2816.971830281671;5099.821506247264;5455.454454554592;5559.749811258503;5578.525825304894;5586.3520261522945;5141.665791723526;5296.252236761859;5359.718229713478;5468.7265636718;5524.281843360337;5112.744362781846;5157.948420515747;5466.863328416822;5584.3857175711455;5621.486570893192;4737.28940658904;4764.523547645208;5297.629165958346;5299.41706412302;5302.3637163540625;4306.418633484564;4819.891424972206;4891.874868780272;4959.479254678206;4994.5754610858485;4915.943072239485;4960.56932103274;4988.55103040728;5670.886582268414;5736.598438109292;3672.889813305605;3926.3521518949055;4051.230266249403;4062.5937406259136;4074.735142215865;4289.699721019623;5369.563479373126;5447.891042179213;5562.694051827189;5679.267823313764;4803.222807561225;5357.79413160667;5412.566994640498;5429.918551221814;5443.482869127427;5108.8249702569365;5162.6902385856965;5363.892722145613;5379.865503362291;5506.779728810803;4706.247000479907;5066.986602679405;5150.716710580804;5244.28014786695;5377.059014672495;5382.596305277041;5417.566594672496;5451.48210919962;5452.765345268428;5494.835154945358;4631.351610774427;5273.367195936512;5292.894142117213;5320;5358.6248962572245 09:26:26 INFO - PROCESS | 1635 | |5;yui.html;665.8768127896336;702.0347022843594;732.3445516262944;780.9726659566915;782.7244372926475;13930.233837138954;14471.710565788679;14596.41614335428;14746.705065898675;14808.851911480895;13833.23917184557;13865.861341386608;14003.299835008234;14015.159090454554;14050;620.0822782282225;879.3756432932626;880.6433394475248;881.6120906801019;884.0364003236471;638.7732355013973;887.8401887660218;902.702108304258;906.714384968736;907.6324088744069;941.2281928818371;941.7363138321281;943;943.32552225159;943.4622265308777;946.9914541013799;947.1049857884302;947.4504787223394;948.1182500274741;948.4214629076258;693.6670398208837;693.9340762627593;694.4513834071078;694.9826254343632;695.478391206595;14804.777928331083;14825.18461484618;14880.702385952329;14942.701145977127;15043.548693539211;13951.441942322295;14128.364223609962;14145.646358841008;14149.575512734635;14227.35976881043;11385.943070284678;11433.428328583537;11455.026322762575;11553.13351498641;11992.811501597462;3669.5779985301765;3680.705543556509;3688.723345749053;3770.057485628593;3847.403652433877;93381.39351187639;95632.9999999993;95681.56477652841;96683.03316966881;97303;10781.568737250422;10804.459777011276;10811.729706757395;11064.834027489595;11269.436528173559;21441.46396340104;22227.110915563197;22307.442313941956;22997.540049198924;23046;7568.013682284566;7642.426817988673;7700.345470634947;7710.845783084307;7858.332041776399;4033.878983630496;4105.577689243028;4127.938080928789;4195.433616461739;4312.5903039211225;3513.7364697647777;3586.8744593939086;3613.6205698401814;3631.1829838286176;3641.817909104534;658.5653468710628;667.1327274543074;674.3189378727423;675.8546912413855;681.4922882452605;638.4636904999822;644.7292137302251;648.2545073165898;676.2459857259219;684.8116767888871;8383.748487545383;9597.040295970348;9712.368696034877;9863.457509836884;9901.504924753734;614.7848253111465;662.8309781005813;675.8715843989626;678.4063944048958;681.4718593090296;4299.279144916729;4841.072870256886;4850.442199147073;4902.0587647411985;4907.114968540975;78878;94638;96139;96183;96562.51718741361;80616.77292400757;83601;84328.15671843325;85760;86554;74415;74584.25415745881;75779.62110189414;76414.23585764071;76830.61584692153;9068.36521443519;9089.636414543344;9128.178463938277;9154.908450915405;9219.985801561688;39062.21875562416;43520.69437916868;46785.362512311905;46947;48713;53330.40008799742;55754.44245557493;59803.70098149481;60438.75301773955;61171.01310179035;10125.89874101279;10439.164866810484;11249.550017999189;11601.941990289995;11900.869417405333;12073.758524829278;12095.879041209475;12145.696357590778;12259.632211033684;12367.814482782585;9537.666181683608;11581.247218930743;11584.594539191088;12122.93938530337;12616.423393903213;12823;13102.344882756015;13207.27359995171;13393.263370514715;13394.263315517741;12469.812952805896;12558.290641009622;12608.432620531914;12763.936180319039;13187.274699891597;10631.681049568526;10656.360618362613;10731.108128321326;10912.836307455236;11053.668389948314;8760.693375731818;8831.293496520135;9498.382605130644;10064.597416103274;10446.791064178522 09:26:26 INFO - PROCESS | 1635 | __end_tp_report 09:26:26 INFO - PROCESS | 1635 | __start_cc_report 09:26:26 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,3310 09:26:26 INFO - PROCESS | 1635 | __end_cc_report 09:26:26 INFO - PROCESS | 1635 | __startTimestamp1487093185998__endTimestamp 09:26:26 INFO - PROCESS | 1635 | 09:26:26 INFO - PROCESS | 1635 | ------- Summary: start ------- 09:26:26 INFO - PROCESS | 1635 | Number of tests: 6 09:26:26 INFO - PROCESS | 1635 | 09:26:26 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5627.41 Median:5768.37 stddev:628.62 (10.9%) stddev-sans-first:599.09 09:26:26 INFO - PROCESS | 1635 | Values: 3124.2 3190.9 3324.2 3329.5 3363.1 5421.4 5541.3 5659.5 5698.2 5711.3 5537.4 5614.8 5661.9 5668.8 5674.1 4517.7 5069.9 5070.4 5147.1 5153.6 5220.2 5227.7 5229.3 5252.9 5262.7 4997.7 5018.4 5019.1 5204.2 5239.2 5090.1 5108.3 5135.0 5146.7 5230.6 5100.5 5108.6 5111.6 5144.5 5200.3 5488.5 5626.7 5631.3 5642.4 5644.8 6053.9 6128.0 6129.7 6179.8 6196.5 6220.8 6261.8 6267.1 6271.2 6282.9 5773.3 5785.5 5786.7 5800.4 5827.4 6125.9 6166.6 6187.7 6189.7 6240.0 5864.9 5931.9 5932.6 5939.7 5945.6 6112.8 6130.3 6130.7 6134.2 6139.1 5962.7 6030.8 6053.0 6054.5 6058.7 5595.7 6087.5 6094.4 6097.0 6107.0 4835.1 4861.8 4867.4 4870.1 4875.4 4335.9 4346.0 4346.5 4357.4 4436.3 5729.8 5739.1 5766.1 5768.4 5774.9 5871.5 5916.3 5919.5 6297.7 6305.4 5670.7 5717.4 5738.9 5761.4 5795.8 4858.7 5412.6 5436.8 5438.9 5439.2 6138.3 6195.0 6264.1 6274.0 6285.4 5070.8 5380.5 6054.9 6108.0 6144.5 6225.1 6249.0 6252.3 6255.0 6258.0 6091.7 6123.8 6133.8 6141.7 6149.8 5966.1 6194.6 6245.9 6248.8 6250.0 5720.3 5772.2 5935.0 5967.2 6000.8 5676.3 5781.2 5848.7 5849.3 5864.8 5640.3 5686.1 5691.4 5693.0 5714.1 5826.0 5832.2 5832.5 5835.2 5846.1 5677.1 5695.7 5699.6 5701.8 5705.5 09:26:26 INFO - PROCESS | 1635 | 09:26:26 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:56510.60 Median:36499.23 stddev:115888.52 (317.5%) stddev-sans-first:116137.23 09:26:26 INFO - PROCESS | 1635 | Values: 468.2 492.7 501.4 501.6 516.6 36458.6 37279.8 37386.6 38997.0 39051.8 37735.6 38200.0 38210.4 38315.0 38488.2 7292.5 8956.6 9171.4 9171.8 9173.7 7259.9 10781.9 10977.0 10989.8 11108.7 10952.8 10984.5 10993.7 11009.2 11025.2 7192.8 7384.2 10900.8 10985.8 10998.9 10499.1 10762.7 10797.8 10911.3 10920.0 36481.0 36803.7 36804.1 36871.3 36933.4 3751.2 3816.2 3821.8 3822.1 3896.6 27649.6 28528.4 28669.6 28778.9 28836.7 2720.3 3044.5 3060.6 3133.8 3175.7 297272.5 297482.0 297735.0 297790.0 298464.5 49873.8 50086.7 50150.5 50203.0 50220.0 53786.0 54078.0 54167.7 54275.2 54439.0 13162.8 13291.9 13344.1 13345.1 13350.8 10276.5 10310.6 10341.9 10343.5 10352.9 12277.6 14028.9 14137.5 14183.6 14201.9 5986.4 6046.1 6075.0 6076.3 6088.3 807.6 823.1 841.7 843.6 860.2 27057.0 27239.9 27321.0 27816.0 28106.3 995.5 1033.7 1034.7 1037.6 1042.2 13240.7 13850.5 13949.2 14082.4 14348.8 518672.0 520546.0 524042.1 524393.0 526142.0 51791.7 51962.0 52003.7 52122.5 52242.5 1298.7 1357.1 1362.4 1363.2 1368.9 25362.5 26833.7 27185.3 27627.6 27742.2 43754.9 44108.0 44280.1 44310.0 44327.6 450426.0 454858.0 455783.0 457069.0 457457.0 34084.7 38879.4 40026.2 40191.6 40255.8 42030.0 42070.0 43800.9 43861.6 45345.3 34854.3 35937.3 36517.5 36983.6 37348.8 27085.8 35537.3 36400.9 36698.6 36903.0 35654.6 36085.3 36659.3 36933.8 37194.6 30588.7 31483.5 32197.0 32419.5 32523.0 19466.5 23670.6 23817.6 24258.9 24366.4 09:26:26 INFO - PROCESS | 1635 | 09:26:26 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:34608.77 Median:2699.65 stddev:101645.11 (3765.1%) stddev-sans-first:101897.71 09:26:26 INFO - PROCESS | 1635 | Values: 355.7 390.8 392.5 401.0 421.4 13595.5 15412.8 19899.8 20181.0 20234.7 21736.8 22075.3 22079.4 22140.9 22141.3 5565.4 5623.6 5705.5 5885.1 5887.7 5636.4 5756.4 5799.6 5803.7 5808.3 5494.4 5695.2 5737.0 5744.8 5773.7 5660.0 5695.7 5718.0 5724.9 5733.2 4788.8 4956.7 5019.3 5042.2 5051.3 25417.5 25769.6 25799.7 25880.9 26127.2 2334.5 2540.7 2573.9 2655.0 2744.3 5916.9 6456.2 9384.8 9691.4 10104.9 1745.3 1756.8 1796.0 1900.1 1905.2 102149.0 108591.0 112920.0 114810.4 116389.0 10133.9 10625.9 10922.8 11346.9 11374.3 15298.6 15309.8 15315.5 15319.5 15334.0 7585.4 7761.5 7842.1 7862.7 7879.3 7177.8 7208.2 7213.4 7344.4 7351.9 3893.5 4673.2 4675.6 4698.3 4757.2 1730.7 1945.6 1980.4 1987.9 1997.7 903.7 986.5 987.3 993.2 1029.8 17766.0 18216.9 21360.0 21363.9 21583.0 986.6 1003.2 1027.8 1028.8 1039.5 9356.6 10480.0 10722.2 10814.4 10971.7 603388.0 607202.0 611453.0 612677.0 626048.1 86266.6 100009.5 110292.0 134867.0 138045.6 892.8 961.7 980.8 993.8 996.3 19115.4 19791.4 19936.7 19986.6 20014.5 44480.1 46905.8 52460.4 53354.0 53854.5 76578.6 76994.0 78208.6 78234.0 78874.6 8097.2 8151.0 8309.7 8335.5 8391.9 11570.0 12801.6 12820.6 13194.9 13219.0 8641.9 11349.7 11358.2 11365.3 11473.5 11102.0 11245.5 11268.8 11412.6 11741.0 11447.9 11588.1 11798.6 11812.6 11815.9 7228.1 7356.9 7505.9 8037.8 8461.6 8351.2 10304.3 11225.4 11794.0 11842.4 09:26:26 INFO - PROCESS | 1635 | 09:26:26 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5356.47 Median:5347.24 stddev:2954.76 (55.3%) stddev-sans-first:2946.99 09:26:26 INFO - PROCESS | 1635 | Values: 1257.6 1357.2 1439.8 1457.8 1475.3 8068.7 8213.4 8459.9 8466.6 8481.3 8259.3 8435.7 8523.0 8544.2 8559.9 1851.4 2201.6 2233.6 2241.0 2259.0 1866.7 1897.6 1972.7 2271.8 2300.9 1829.1 1936.9 2219.0 2240.8 2245.6 2196.4 2233.7 2238.4 2249.0 2253.5 2225.3 2230.6 2258.5 2262.4 2270.1 8376.5 8424.2 8444.9 8467.8 8487.4 4950.6 5107.6 5129.7 5132.8 5133.1 3195.6 3243.7 3388.6 3431.4 3446.4 1225.9 1249.4 1663.6 1718.3 1740.1 10620.9 10874.3 10876.6 10876.8 10896.0 4427.0 4534.0 4564.6 4633.8 4673.9 8765.7 8821.0 8821.8 8822.4 8843.3 5097.4 5109.9 5111.1 5113.0 5117.8 4557.5 4700.3 4774.0 4774.2 4792.4 3372.8 3435.9 3460.8 3507.9 3515.3 994.9 1108.4 1113.4 1114.2 1136.9 648.7 651.7 704.3 718.6 722.3 5368.8 5429.1 5574.4 5574.4 5596.5 789.5 893.7 949.8 964.6 1004.6 3334.6 3649.4 3755.2 3793.7 3828.8 9622.4 9897.8 9909.0 9909.9 9934.8 9544.3 9600.0 9756.0 9853.8 9892.6 6401.4 7400.7 7597.2 7602.4 7612.9 5325.6 5377.3 5460.7 5481.9 5516.9 9014.0 9158.9 9174.0 9182.8 9196.8 8734.3 8993.6 9200.0 9267.3 9274.0 7558.9 7670.1 7699.3 7765.4 7826.1 7605.7 7800.1 7804.8 7813.3 7824.5 6831.2 6996.9 7009.6 7037.0 7067.8 6846.5 6915.1 6924.6 6928.8 6950.2 6081.0 6206.7 6290.9 6303.6 6318.9 4867.4 4872.0 4929.6 4970.8 5070.5 4834.0 4856.7 4932.5 4997.6 5008.1 09:26:26 INFO - PROCESS | 1635 | 09:26:26 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4411.81 Median:4859.28 stddev:1285.33 (26.5%) stddev-sans-first:1252.24 09:26:26 INFO - PROCESS | 1635 | Values: 349.1 352.5 362.1 366.4 366.6 5058.6 5180.4 5181.5 5269.6 5370.2 4383.9 4696.6 4711.4 5008.9 5114.2 4070.6 4087.8 4103.2 4127.9 4151.7 3247.4 4093.7 4111.3 4119.4 4140.6 3332.3 4052.1 4065.3 4069.3 4111.4 4051.0 4072.0 4073.9 4078.6 4100.7 3613.8 3621.8 3622.9 3639.9 3658.0 4894.1 4961.2 5060.9 5064.8 5128.3 4678.2 4704.7 4706.6 4714.5 4733.0 5468.7 5472.6 5489.5 5507.7 5560.8 3572.5 3932.7 3933.8 3994.8 4006.3 4786.9 5441.5 5519.8 5535.6 5777.9 3138.7 3810.7 3818.3 3855.6 4041.3 3617.5 3963.7 4123.4 4177.5 4187.9 4400.1 4407.2 4558.7 4790.2 4790.9 4812.2 5475.7 5478.2 5497.3 5509.8 1200.8 1203.4 1308.4 1320.5 1337.9 796.9 883.8 966.6 969.0 977.2 4792.5 4812.9 4818.5 4823.5 4826.7 4772.2 5216.8 5281.5 5284.4 5309.7 4305.5 4647.1 4938.3 4939.7 4972.3 2515.9 2784.8 2811.3 2812.8 2817.0 5099.8 5455.5 5559.7 5578.5 5586.4 5141.7 5296.3 5359.7 5468.7 5524.3 5112.7 5157.9 5466.9 5584.4 5621.5 4737.3 4764.5 5297.6 5299.4 5302.4 4306.4 4819.9 4891.9 4959.5 4994.6 4915.9 4960.6 4988.6 5670.9 5736.6 3672.9 3926.4 4051.2 4062.6 4074.7 4289.7 5369.6 5447.9 5562.7 5679.3 4803.2 5357.8 5412.6 5429.9 5443.5 5108.8 5162.7 5363.9 5379.9 5506.8 4706.2 5067.0 5150.7 5244.3 5377.1 5382.6 5417.6 5451.5 5452.8 5494.8 4631.4 5273.4 5292.9 5320.0 5358.6 09:26:26 INFO - PROCESS | 1635 | 09:26:26 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:19000.23 Median:29118.76 stddev:26863.14 (92.3%) stddev-sans-first:26903.22 09:26:26 INFO - PROCESS | 1635 | Values: 665.9 702.0 732.3 781.0 782.7 13930.2 14471.7 14596.4 14746.7 14808.9 13833.2 13865.9 14003.3 14015.2 14050.0 620.1 879.4 880.6 881.6 884.0 638.8 887.8 902.7 906.7 907.6 941.2 941.7 943.0 943.3 943.5 947.0 947.1 947.5 948.1 948.4 693.7 693.9 694.5 695.0 695.5 14804.8 14825.2 14880.7 14942.7 15043.5 13951.4 14128.4 14145.6 14149.6 14227.4 11385.9 11433.4 11455.0 11553.1 11992.8 3669.6 3680.7 3688.7 3770.1 3847.4 93381.4 95633.0 95681.6 96683.0 97303.0 10781.6 10804.5 10811.7 11064.8 11269.4 21441.5 22227.1 22307.4 22997.5 23046.0 7568.0 7642.4 7700.3 7710.8 7858.3 4033.9 4105.6 4127.9 4195.4 4312.6 3513.7 3586.9 3613.6 3631.2 3641.8 658.6 667.1 674.3 675.9 681.5 638.5 644.7 648.3 676.2 684.8 8383.7 9597.0 9712.4 9863.5 9901.5 614.8 662.8 675.9 678.4 681.5 4299.3 4841.1 4850.4 4902.1 4907.1 78878.0 94638.0 96139.0 96183.0 96562.5 80616.8 83601.0 84328.2 85760.0 86554.0 74415.0 74584.3 75779.6 76414.2 76830.6 9068.4 9089.6 9128.2 9154.9 9220.0 39062.2 43520.7 46785.4 46947.0 48713.0 53330.4 55754.4 59803.7 60438.8 61171.0 10125.9 10439.2 11249.6 11601.9 11900.9 12073.8 12095.9 12145.7 12259.6 12367.8 9537.7 11581.2 11584.6 12122.9 12616.4 12823.0 13102.3 13207.3 13393.3 13394.3 12469.8 12558.3 12608.4 12763.9 13187.3 10631.7 10656.4 10731.1 10912.8 11053.7 8760.7 8831.3 9498.4 10064.6 10446.8 09:26:26 INFO - PROCESS | 1635 | -------- Summary: end -------- 09:26:26 INFO - PROCESS | 1635 | 09:26:26 INFO - TEST-INFO | 1635: exit 0 09:26:26 INFO - TEST-OK | dromaeo_css | took 1120394ms 09:26:26 INFO - TEST-START | kraken 09:26:26 INFO - operating with platform_type : mac_ 09:26:26 INFO - Initialising browser for kraken test... 09:26:26 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmppiQ139/profile http://localhost:49240/getInfo.html) 09:26:29 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 09:26:29 INFO - PROCESS | 1644 | colorDepth:24 09:26:29 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 09:26:29 INFO - PROCESS | 1644 | __metrics 09:26:29 INFO - PROCESS | 1644 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:26:29 INFO - PROCESS | 1644 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:26:29 INFO - TEST-INFO | 1644: exit 0 09:26:29 INFO - Browser initialized. 09:26:29 INFO - Running cycle 1/1 for kraken test... 09:26:29 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/tmppiQ139/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:26:42 INFO - PROCESS | 1645 | RSS: Main: 228839424 09:26:42 INFO - PROCESS | 1645 | 09:26:44 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 09:26:45 INFO - PROCESS | 1645 | RSS: Main: 288915456 09:26:45 INFO - PROCESS | 1645 | 09:26:48 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 09:26:49 INFO - PROCESS | 1645 | RSS: Main: 291491840 09:26:49 INFO - PROCESS | 1645 | 09:26:52 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 09:26:53 INFO - PROCESS | 1645 | RSS: Main: 382509056 09:26:53 INFO - PROCESS | 1645 | 09:26:55 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 09:26:56 INFO - PROCESS | 1645 | RSS: Main: 285921280 09:26:56 INFO - PROCESS | 1645 | 09:26:58 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:26:58 INFO - PROCESS | 1645 | RSS: Main: 288169984 09:26:58 INFO - PROCESS | 1645 | 09:27:01 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 09:27:02 INFO - PROCESS | 1645 | RSS: Main: 329986048 09:27:02 INFO - PROCESS | 1645 | 09:27:05 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 09:27:05 INFO - PROCESS | 1645 | RSS: Main: 331563008 09:27:05 INFO - PROCESS | 1645 | 09:27:08 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 09:27:09 INFO - PROCESS | 1645 | RSS: Main: 330133504 09:27:09 INFO - PROCESS | 1645 | 09:27:11 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:27:11 INFO - PROCESS | 1645 | RSS: Main: 306257920 09:27:11 INFO - PROCESS | 1645 | 09:27:13 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:27:14 INFO - PROCESS | 1645 | RSS: Main: 310804480 09:27:14 INFO - PROCESS | 1645 | 09:27:16 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:27:17 INFO - PROCESS | 1645 | RSS: Main: 313741312 09:27:17 INFO - PROCESS | 1645 | 09:27:20 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:27:21 INFO - PROCESS | 1645 | RSS: Main: 330850304 09:27:21 INFO - PROCESS | 1645 | 09:27:24 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:27:24 INFO - PROCESS | 1645 | RSS: Main: 324792320 09:27:24 INFO - PROCESS | 1645 | 09:27:26 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 09:27:26 INFO - PROCESS | 1645 | RSS: Main: 369864704 09:27:26 INFO - PROCESS | 1645 | 09:27:26 INFO - PROCESS | 1645 | __start_tp_report 09:27:26 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 09:27:26 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 09:27:26 INFO - PROCESS | 1645 | |i|pagename|runs| 09:27:26 INFO - PROCESS | 1645 | |0;ai-astar;113;114;114;113;113;114;114;115;118;165 09:27:26 INFO - PROCESS | 1645 | |1;audio-beat-detection;142;115;118;154;115;107;116;114;135;115 09:27:26 INFO - PROCESS | 1645 | |2;audio-dft;205;261;189;186;183;182;183;184;184;184 09:27:26 INFO - PROCESS | 1645 | |3;audio-fft;81;61;63;108;87;111;63;60;80;61 09:27:26 INFO - PROCESS | 1645 | |4;audio-oscillator;91;73;109;98;100;74;85;69;68;87 09:27:26 INFO - PROCESS | 1645 | |5;imaging-gaussian-blur;91;92;90;90;91;90;90;90;90;98 09:27:26 INFO - PROCESS | 1645 | |6;imaging-darkroom;98;97;100;97;97;97;98;98;97;96 09:27:26 INFO - PROCESS | 1645 | |7;imaging-desaturate;85;107;132;85;87;87;85;88;88;86 09:27:26 INFO - PROCESS | 1645 | |8;json-parse-financial;60;61;57;59;60;61;60;60;59;61 09:27:26 INFO - PROCESS | 1645 | |9;json-stringify-tinderbox;66;71;69;77;79;75;70;69;70;71 09:27:26 INFO - PROCESS | 1645 | |10;stanford-crypto-aes;90;92;97;93;96;94;97;95;95;99 09:27:26 INFO - PROCESS | 1645 | |11;stanford-crypto-ccm;148;147;146;138;138;164;155;152;188;145 09:27:26 INFO - PROCESS | 1645 | |12;stanford-crypto-pbkdf2;184;186;184;183;183;184;180;183;181;188 09:27:26 INFO - PROCESS | 1645 | |13;stanford-crypto-sha256-iterative;68;71;69;70;68;67;68;69;69;67 09:27:26 INFO - PROCESS | 1645 | __end_tp_report 09:27:26 INFO - PROCESS | 1645 | __start_cc_report 09:27:26 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,2211 09:27:26 INFO - PROCESS | 1645 | __end_cc_report 09:27:26 INFO - PROCESS | 1645 | __startTimestamp1487093246762__endTimestamp 09:27:26 INFO - PROCESS | 1645 | 09:27:26 INFO - PROCESS | 1645 | ------- Summary: start ------- 09:27:26 INFO - PROCESS | 1645 | Number of tests: 14 09:27:26 INFO - PROCESS | 1645 | 09:27:26 INFO - PROCESS | 1645 | [#0] ai-astar Cycles:10 Average:119.30 Median:114.00 stddev:16.12 (14.1%) stddev-sans-first:16.94 09:27:26 INFO - PROCESS | 1645 | Values: 113.0 114.0 114.0 113.0 113.0 114.0 114.0 115.0 118.0 165.0 09:27:26 INFO - PROCESS | 1645 | 09:27:26 INFO - PROCESS | 1645 | [#1] audio-beat-detection Cycles:10 Average:123.10 Median:117.00 stddev:15.16 (13.0%) stddev-sans-first:14.46 09:27:26 INFO - PROCESS | 1645 | Values: 142.0 115.0 118.0 154.0 115.0 107.0 116.0 114.0 135.0 115.0 09:27:26 INFO - PROCESS | 1645 | 09:27:26 INFO - PROCESS | 1645 | [#2] audio-dft Cycles:10 Average:194.10 Median:185.00 stddev:24.46 (13.2%) stddev-sans-first:25.62 09:27:26 INFO - PROCESS | 1645 | Values: 205.0 261.0 189.0 186.0 183.0 182.0 183.0 184.0 184.0 184.0 09:27:26 INFO - PROCESS | 1645 | 09:27:26 INFO - PROCESS | 1645 | [#3] audio-fft Cycles:10 Average:77.50 Median:63.00 stddev:19.53 (31.0%) stddev-sans-first:20.67 09:27:26 INFO - PROCESS | 1645 | Values: 81.0 61.0 63.0 108.0 87.0 111.0 63.0 60.0 80.0 61.0 09:27:26 INFO - PROCESS | 1645 | 09:27:26 INFO - PROCESS | 1645 | [#4] audio-oscillator Cycles:10 Average:85.40 Median:79.50 stddev:14.21 (17.9%) stddev-sans-first:14.93 09:27:26 INFO - PROCESS | 1645 | Values: 91.0 73.0 109.0 98.0 100.0 74.0 85.0 69.0 68.0 87.0 09:27:26 INFO - PROCESS | 1645 | 09:27:26 INFO - PROCESS | 1645 | [#5] imaging-gaussian-blur Cycles:10 Average:91.20 Median:90.50 stddev:2.49 (2.7%) stddev-sans-first:2.64 09:27:26 INFO - PROCESS | 1645 | Values: 91.0 92.0 90.0 90.0 91.0 90.0 90.0 90.0 90.0 98.0 09:27:26 INFO - PROCESS | 1645 | 09:27:26 INFO - PROCESS | 1645 | [#6] imaging-darkroom Cycles:10 Average:97.50 Median:97.00 stddev:1.08 (1.1%) stddev-sans-first:1.13 09:27:26 INFO - PROCESS | 1645 | Values: 98.0 97.0 100.0 97.0 97.0 97.0 98.0 98.0 97.0 96.0 09:27:26 INFO - PROCESS | 1645 | 09:27:26 INFO - PROCESS | 1645 | [#7] imaging-desaturate Cycles:10 Average:93.00 Median:86.50 stddev:15.20 (17.6%) stddev-sans-first:15.85 09:27:26 INFO - PROCESS | 1645 | Values: 85.0 107.0 132.0 85.0 87.0 87.0 85.0 88.0 88.0 86.0 09:27:26 INFO - PROCESS | 1645 | 09:27:26 INFO - PROCESS | 1645 | [#8] json-parse-financial Cycles:10 Average:59.80 Median:60.00 stddev:1.23 (2.0%) stddev-sans-first:1.30 09:27:26 INFO - PROCESS | 1645 | Values: 60.0 61.0 57.0 59.0 60.0 61.0 60.0 60.0 59.0 61.0 09:27:26 INFO - PROCESS | 1645 | 09:27:26 INFO - PROCESS | 1645 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:71.00 stddev:4.03 (5.7%) stddev-sans-first:3.71 09:27:26 INFO - PROCESS | 1645 | Values: 66.0 71.0 69.0 77.0 79.0 75.0 70.0 69.0 70.0 71.0 09:27:26 INFO - PROCESS | 1645 | 09:27:26 INFO - PROCESS | 1645 | [#10] stanford-crypto-aes Cycles:10 Average:94.80 Median:95.50 stddev:2.66 (2.8%) stddev-sans-first:2.18 09:27:26 INFO - PROCESS | 1645 | Values: 90.0 92.0 97.0 93.0 96.0 94.0 97.0 95.0 95.0 99.0 09:27:26 INFO - PROCESS | 1645 | 09:27:26 INFO - PROCESS | 1645 | [#11] stanford-crypto-ccm Cycles:10 Average:152.10 Median:150.00 stddev:14.78 (9.9%) stddev-sans-first:15.61 09:27:26 INFO - PROCESS | 1645 | Values: 148.0 147.0 146.0 138.0 138.0 164.0 155.0 152.0 188.0 145.0 09:27:26 INFO - PROCESS | 1645 | 09:27:26 INFO - PROCESS | 1645 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.60 Median:184.00 stddev:2.27 (1.2%) stddev-sans-first:2.40 09:27:26 INFO - PROCESS | 1645 | Values: 184.0 186.0 184.0 183.0 183.0 184.0 180.0 183.0 181.0 188.0 09:27:26 INFO - PROCESS | 1645 | 09:27:26 INFO - PROCESS | 1645 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:69.00 stddev:1.26 (1.8%) stddev-sans-first:1.32 09:27:26 INFO - PROCESS | 1645 | Values: 68.0 71.0 69.0 70.0 68.0 67.0 68.0 69.0 69.0 67.0 09:27:26 INFO - PROCESS | 1645 | -------- Summary: end -------- 09:27:26 INFO - PROCESS | 1645 | 09:27:27 INFO - TEST-INFO | 1645: exit 0 09:27:27 INFO - TEST-OK | kraken | took 61042ms 09:27:27 INFO - SUITE-END | took 1181s 09:27:27 INFO - Completed test suite (00:19:41) 09:27:27 INFO - javascript benchmark 09:27:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7026.1163803323325, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3124.1877111950885, 3190.9469874941265, 3324.16895776056, 3329.4518049107173, 3363.058343663775, 5421.403645598981, 5541.33503979522, 5659.490645841874, 5698.173764804106, 5711.257536520252, 5537.446255374461, 5614.803481878135, 5661.943380566193, 5668.801591944282, 5674.120511320752, 4517.6672881500035, 5069.898602027957, 5070.416902056256, 5147.073526765181, 5153.587712982971, 5220.190870415092, 5227.73861306935, 5229.346331708536, 5252.868678283055, 5262.684238945675, 4997.6501644884875, 5018.447970723218, 5019.121653710623, 5204.245384419276, 5239.21411788231, 5090.058339207221, 5108.2593024011685, 5134.973005398935, 5146.742662866879, 5230.607704422184, 5100.515451032146, 5108.6168537359845, 5111.642185047048, 5144.485551444863, 5200.323957885451, 5488.451154884519, 5626.718664066781, 5631.2960879890015, 5642.4075472075665, 5644.814588936329, 6053.909191362133, 6127.9837278401355, 6129.662868542228, 6179.752809887599, 6196.504279657616, 6220.782272620482, 6261.78083767066, 6267.091271765614, 6271.153394291803, 6282.874342513124, 5773.278340207474, 5785.450382213683, 5786.710664466844, 5800.4489573490455, 5827.388124246978, 6125.907024958145, 6166.568340216142, 6187.690615469299, 6189.690515474208, 6240.032794916795, 5864.853378665567, 5931.881362372815, 5932.644041357532, 5939.673317967553, 5945.583809133319, 6112.847178820476, 6130.325664176937, 6130.6628135452975, 6134.2332208473945, 6139.10982907476, 5962.731677074542, 6030.788922387783, 6052.969735151296, 6054.485368743618, 6058.727357268934, 5595.748191331319, 6087.452129308295, 6094.421030002142, 6097.0, 6107.0, 4835.081334546425, 4861.756912154378, 4867.3915760529935, 4870.14772414826, 4875.414950205998, 4335.891602709958, 4345.978695006671, 4346.500152482505, 4357.411749413852, 4436.334549817553, 5729.7708091676695, 5739.139129130664, 5766.135079738073, 5768.3654797972185, 5774.942250577525, 5871.530277577784, 5916.319623243296, 5919.526437884959, 6297.6536290503545, 6305.432511074026, 5670.716464176775, 5717.371089180187, 5738.913916291259, 5761.423857614289, 5795.7971470999155, 4858.659893807399, 5412.5940554458575, 5436.836894893159, 5438.91841622376, 5439.211314359396, 6138.324784273726, 6195.0, 6264.123022776815, 6273.96863015682, 6285.402886725754, 5070.8225212117395, 5380.542653874464, 6054.9091763624465, 6108.038540470994, 6144.53915956314, 6225.128482012612, 6248.968755156195, 6252.280987686474, 6255.0, 6258.030005349178, 6091.664958427329, 6123.8162855114415, 6133.815985520441, 6141.723622436912, 6149.7847575334645, 5966.134910438049, 6194.566380353332, 6245.937540624535, 6248.781292654917, 6249.968750156402, 5720.256366672306, 5772.19189313488, 5935.0207215808905, 5967.164596956516, 6000.819975400745, 5676.262085928803, 5781.248437703071, 5848.6783226921225, 5849.268841394825, 5864.765409383747, 5640.266765320482, 5686.118651609007, 5691.430856914276, 5693.032184528723, 5714.05718056516, 5825.970870145622, 5832.183494310715, 5832.533397328119, 5835.212246346859, 5846.093855452412, 5677.148427735872, 5695.743691533974, 5699.601027928005, 5701.771929122954, 5705.515031222392], "value": 5587.050260691665, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [468.15498026062966, 492.7093015121078, 501.39331408995116, 501.566145284329, 516.6280278199695, 36458.635413645854, 37279.81360093199, 37386.62613373865, 38997.0, 39051.80474097626, 37735.62264377355, 38200.0, 38210.4268435973, 38315.042123946914, 38488.23023539528, 7292.4530660200435, 8956.641734330635, 9171.358004939624, 9171.816563668723, 9173.724788256362, 7259.927400726005, 10781.892181078207, 10976.9692606868, 10989.780204395907, 11108.722281942975, 10952.835707464394, 10984.450777461136, 10993.725156871062, 11009.174311926598, 11025.173112016591, 7192.820179495502, 7384.2246564111065, 10900.836487452694, 10985.835212471779, 10998.945005275002, 10499.107575856062, 10762.677119686421, 10797.784044319149, 10911.290766100177, 10920.017198452177, 36481.0, 36803.711870084684, 36804.0798980025, 36871.262574748354, 36933.44599830978, 3751.249750050001, 3816.217675376541, 3821.751586146919, 3822.140018495844, 3896.59085795993, 27649.585256221173, 28528.429431411463, 28669.56995645046, 28778.856105719547, 28836.711632883824, 2720.2519307190482, 3044.528098144791, 3060.6021217241614, 3133.8119712817074, 3175.682431756829, 297272.5136374326, 297482.0, 297735.0, 297790.02209978056, 298464.5076774624, 49873.750631247334, 50086.749566251936, 50150.498495015316, 50202.99999999927, 50220.0, 53786.0, 54078.046976142134, 54167.72916135394, 54275.18587221195, 54439.0, 13162.802557961639, 13291.93354033243, 13344.132631378929, 13345.132566383154, 13350.799738003938, 10276.486175691185, 10310.587576496857, 10341.948290258651, 10343.482825858677, 10352.948235258775, 12277.570285039981, 14028.929855350862, 14137.5051873186, 14183.64540886486, 14201.857981420053, 5986.431289027535, 6046.123312119718, 6075.0, 6076.3316035236085, 6088.299845517734, 807.6244546285888, 823.1315961660385, 841.7432683031611, 843.6498852976069, 860.2300940658076, 27057.0, 27239.86380068087, 27321.0, 27816.0, 28106.29734256619, 995.4773743784571, 1033.684726158529, 1034.7479549744742, 1037.5953378182514, 1042.1610603464133, 13240.668983275495, 13850.515231967034, 13949.232792196324, 14082.366293516816, 14348.784768228485, 518672.0, 520546.0, 524042.0766338684, 524393.0, 526142.0, 51791.70520737012, 51962.0, 52003.73998129985, 52122.47877521252, 52242.47757522376, 1298.7402519496138, 1357.1178733823094, 1362.3937347880296, 1363.2093385836192, 1368.9452421903013, 25362.492750144524, 26833.736279556757, 27185.32036699098, 27627.58558621582, 27742.167734967985, 43754.90612734707, 44108.0, 44280.11439771251, 44310.0, 44327.55672443234, 450426.0, 454858.0, 455783.0, 457069.0, 457457.0, 34084.659153408145, 38879.41680874732, 40026.19489199578, 40191.55498457802, 40255.798721006206, 42030.0, 42070.0, 43800.90497737582, 43861.56138438575, 45345.31982020338, 34854.30291394208, 35937.28125437424, 36517.45223821592, 36983.63016369802, 37348.81325593355, 27085.781139849147, 35537.28925421425, 36400.90797276086, 36698.63301367059, 36903.0, 35654.64345356617, 36085.27829443344, 36659.26681466409, 36933.81533092317, 37194.62805371912, 30588.694113058584, 31483.52774708427, 32197.0, 32419.513707294882, 32523.0, 19466.513337166685, 23670.644940325565, 23817.629118644425, 24258.87870560636, 24366.390840229134], "value": 18334.645643121356, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.6532380928597, 390.8182695046805, 392.50740320897273, 400.959904009599, 421.3658444041717, 13595.52415665452, 15412.768808467868, 19899.801001989978, 20181.0, 20234.69647955282, 21736.782632173672, 22075.33773986779, 22079.44801379966, 22140.8892955535, 22141.335759927188, 5565.387807341189, 5623.578231632634, 5705.4579814917515, 5885.11723241515, 5887.67617781024, 5636.436356364372, 5756.424357564231, 5799.565032622549, 5803.680797556132, 5808.3030036395485, 5494.395616482184, 5695.174199741035, 5737.0, 5744.79893203738, 5773.711314434283, 5660.0, 5695.746935674187, 5717.9987004548075, 5724.8855022899725, 5733.226014488033, 4788.784504697297, 4956.677815941988, 5019.347484826949, 5042.218456139304, 5051.343325367716, 25417.491650167078, 25769.61345579818, 25799.742002579922, 25880.87059564709, 26127.216183514523, 2334.4514039200776, 2540.720520742717, 2573.9099131530493, 2655.0, 2744.300203448108, 5916.852078698024, 6456.192975878015, 9384.765380865465, 9691.418514889128, 10104.89702700139, 1745.3454954392103, 1756.8017657498217, 1795.9640807183914, 1900.0594705620667, 1905.199816077251, 102149.0, 108591.0, 112920.0, 114810.42594787056, 116389.0, 10133.949330253301, 10625.893741062646, 10922.836157457645, 11346.94326528362, 11374.31754094757, 15298.617534561503, 15309.770353444706, 15315.46395876139, 15319.540413787605, 15334.0, 7585.393168546503, 7761.534307941542, 7842.098158711707, 7862.72480463181, 7879.290863822283, 7177.820554486075, 7208.207097219301, 7213.350798428167, 7344.375728063068, 7351.926480735124, 3893.4886997886206, 4673.158831410311, 4675.638802404832, 4698.285125929011, 4757.16749568831, 1730.7144321186863, 1945.6497830390665, 1980.4256765537832, 1987.8509111816381, 1997.6803711406396, 903.7333986473875, 986.541258314887, 987.2694206287439, 993.1657407777501, 1029.799189158107, 17766.0, 18216.908915455337, 21360.0, 21363.931803409767, 21583.0, 986.5856340337076, 1003.2325271167563, 1027.8304079826894, 1028.83024300991, 1039.4750650921237, 9356.578953947088, 10479.963461614801, 10722.195835312383, 10814.405207713651, 10971.670849874517, 603388.0, 607202.0, 611453.0, 612677.0, 626048.0508703332, 86266.56866715626, 100009.49995249977, 110292.0, 134867.0, 138045.55929356592, 892.8169725206317, 961.7162936933621, 980.7695191629962, 993.8062077894886, 996.3125443444145, 19115.426537203904, 19791.40625781229, 19936.70094948548, 19986.60026799427, 20014.49963750918, 44480.11039779251, 46905.765471172424, 52460.42618721444, 53354.0, 53854.461455384946, 76578.61710691411, 76994.0, 78208.60895695485, 78234.0, 78874.6056269715, 8097.2307630774985, 8151.0, 8309.709160179365, 8335.541545214828, 8391.874121888299, 11570.0, 12801.55194568223, 12820.551280705131, 13194.868051319749, 13219.0, 8641.913580864111, 11349.716257093309, 11358.204925655125, 11365.261258018203, 11473.541058357572, 11102.0, 11245.493952772309, 11268.774624507629, 11412.600558980395, 11741.0, 11447.885521144683, 11588.130890183102, 11798.587049453228, 11812.586559470377, 11815.881841181477, 7228.096487939008, 7356.926430735624, 7505.924940750522, 8037.8392432152195, 8461.576921153795, 8351.20663536963, 10304.330218536072, 11225.4387280634, 11794.0, 11842.407879605813], "value": 9388.633906083032, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1257.6352857671272, 1357.2263809628516, 1439.8128243328365, 1457.8118833150982, 1475.2623688155918, 8068.6772529098835, 8213.425060245785, 8459.87310190347, 8466.619002144902, 8481.279091277242, 8259.29795967342, 8435.662573497068, 8523.0, 8544.23101920827, 8559.887214662102, 1851.3890416162635, 2201.581699477102, 2233.62028455163, 2240.9775902241013, 2259.0, 1866.7199920011972, 1897.6394485047797, 1972.7336809530673, 2271.761465046171, 2300.8849557522058, 1829.0580351119183, 1936.9225230990744, 2218.955620887581, 2240.7759224077545, 2245.62947113726, 2196.439907823503, 2233.7096177496983, 2238.373255488466, 2249.044156233594, 2253.5042290696183, 2225.254539729193, 2230.6170174491917, 2258.525709600986, 2262.400463877074, 2270.1146552844402, 8376.49741015541, 8424.199701028392, 8444.873326900102, 8467.830643387098, 8487.363447741382, 4950.603951683856, 5107.565856902131, 5129.691928558231, 5132.8460146195675, 5133.076046311662, 3195.6325022622464, 3243.724283435911, 3388.5594872666643, 3431.3995050865997, 3446.3796516627, 1225.914186006971, 1249.443997421148, 1663.6007358234058, 1718.3384397007117, 1740.0951505217342, 10620.946895265552, 10874.293170943942, 10876.6193183239, 10876.836847447295, 10896.0, 4426.9778651106535, 4534.0251845852645, 4564.6348292136545, 4633.791479383436, 4673.906521869543, 8765.737027889047, 8820.955895220482, 8821.823563528693, 8822.426542274732, 8843.336749743796, 5097.388313402415, 5109.897802043938, 5111.105556527593, 5112.9744351278005, 5117.8464646060675, 4557.5442455754155, 4700.271457923959, 4773.9761301193275, 4774.164521208774, 4792.448868380111, 3372.7639065265685, 3435.9484607730906, 3460.8096554689732, 3507.8947631571095, 3515.2969406118877, 994.8656931314325, 1108.3682301088302, 1113.4265853085667, 1114.2200459678259, 1136.9033632141195, 648.7307767276621, 651.726274964516, 704.3479558489249, 718.6442710858054, 722.3101937649532, 5368.8389348319615, 5429.131338985822, 5574.358948720868, 5574.414686457863, 5596.4815832875365, 789.4749991255751, 893.718478679214, 949.7530642033121, 964.5996911281879, 1004.5630150884389, 3334.5665063541583, 3649.4160934250394, 3755.2301778135416, 3793.677537409296, 3828.770273783582, 9622.37454565451, 9897.80204395908, 9908.950455247677, 9909.900900991042, 9934.80130397388, 9544.284178686627, 9600.039996000343, 9756.0, 9853.802923941481, 9892.55483503244, 6401.391867772554, 7400.703971841173, 7597.164311925684, 7602.391808655294, 7612.885806712793, 5325.640301178158, 5377.300950876361, 5460.672359658433, 5481.890362192814, 5516.862078447911, 9014.0, 9158.862617060882, 9174.0, 9182.770430739285, 9196.770080747767, 8734.301255899385, 8993.595288211915, 9200.033996430546, 9267.304952128752, 9274.026227246042, 7558.924410756042, 7670.117936437381, 7699.307062364414, 7765.41759368061, 7826.060872695313, 7605.6577454013595, 7800.063992320957, 7804.84390312202, 7813.257740514642, 7824.530528168328, 6831.248562658203, 6996.930030699831, 7009.649517524205, 7036.964815175891, 7067.752628657972, 6846.452283817183, 6915.101036865377, 6924.58452492852, 6928.792136235921, 6950.235474097946, 6080.969595152173, 6206.72069756853, 6290.937090629218, 6303.558750887394, 6318.9052164216655, 4867.415910090812, 4871.951280487291, 4929.5809856161195, 4970.751462426936, 5070.518300761421, 4833.975830120827, 4856.732879691651, 4932.53140951609, 4997.575206107522, 5008.073506401327], "value": 4314.182515314811, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.13414731465963, 352.50473085315133, 362.06406439354276, 366.39178962921557, 366.6205477330963, 5058.5953123750105, 5180.430152683201, 5181.507756763111, 5269.5520880725135, 5370.180655156045, 4383.868483945486, 4696.64775141864, 4711.363965864607, 5008.9230815374685, 5114.156164232897, 4070.633642972132, 4087.7751723655188, 4103.199876024177, 4127.8968025799295, 4151.720141088536, 3247.3829972305302, 4093.713440059197, 4111.259973204822, 4119.423280740699, 4140.60664236897, 3332.316875040623, 4052.1432390164864, 4065.272259289805, 4069.3285607874664, 4111.362738775495, 4050.9594904050878, 4071.9687509365813, 4073.8574149904907, 4078.5921407859123, 4100.7334523255895, 3613.8012409317475, 3621.8410108765206, 3622.8913132606062, 3639.9272014559824, 3657.9817100914593, 4894.089086549445, 4961.231009193545, 5060.898782024376, 5064.772085256172, 5128.282040514331, 4678.193084149517, 4704.694194877322, 4706.576408123286, 4714.481407045222, 4732.952670473285, 5468.72656367184, 5472.619410556381, 5489.478499542537, 5507.7246137693355, 5560.833175004756, 3572.464130380438, 3932.7218653937584, 3933.7639741615308, 3994.840206391741, 4006.318925782624, 4786.856394308176, 5441.455854414567, 5519.806806761784, 5535.612507124543, 5777.851071166225, 3138.6660669215494, 3810.65704086628, 3818.312703713358, 3855.609522096262, 4041.2725709372594, 3617.529721136288, 3963.7027222958395, 4123.36087906375, 4177.456930599003, 4187.937180942288, 4400.107953579992, 4407.228734971432, 4558.652294068557, 4790.233562629963, 4790.928136077961, 4812.230043193073, 5475.67145971243, 5478.178273259043, 5497.257870187554, 5509.834704958857, 1200.7572162811448, 1203.4343858386544, 1308.4177540994356, 1320.518010926005, 1337.936340609218, 796.8884356190138, 883.8232353529191, 966.5553845231132, 968.970930872075, 977.1694060048901, 4792.496787837227, 4812.855614331575, 4818.494058123846, 4823.517648235148, 4826.693714805369, 4772.184411197411, 5216.7913283469, 5281.471852814765, 5284.445133261029, 5309.716831752431, 4305.547917468621, 4647.094691176601, 4938.333325001079, 4939.728314942641, 4972.254161875748, 2515.9496810063697, 2784.805063645566, 2811.2971757060736, 2812.831230126158, 2816.971830281671, 5099.821506247264, 5455.454454554592, 5559.749811258503, 5578.525825304894, 5586.3520261522945, 5141.665791723526, 5296.252236761859, 5359.718229713478, 5468.7265636718, 5524.281843360337, 5112.744362781846, 5157.948420515747, 5466.863328416822, 5584.3857175711455, 5621.486570893192, 4737.28940658904, 4764.523547645208, 5297.629165958346, 5299.41706412302, 5302.3637163540625, 4306.418633484564, 4819.891424972206, 4891.874868780272, 4959.479254678206, 4994.5754610858485, 4915.943072239485, 4960.56932103274, 4988.55103040728, 5670.886582268414, 5736.598438109292, 3672.889813305605, 3926.3521518949055, 4051.230266249403, 4062.5937406259136, 4074.735142215865, 4289.699721019623, 5369.563479373126, 5447.891042179213, 5562.694051827189, 5679.267823313764, 4803.222807561225, 5357.79413160667, 5412.566994640498, 5429.918551221814, 5443.482869127427, 5108.8249702569365, 5162.6902385856965, 5363.892722145613, 5379.865503362291, 5506.779728810803, 4706.247000479907, 5066.986602679405, 5150.716710580804, 5244.28014786695, 5377.059014672495, 5382.596305277041, 5417.566594672496, 5451.48210919962, 5452.765345268428, 5494.835154945358, 4631.351610774427, 5273.367195936512, 5292.894142117213, 5320.0, 5358.6248962572245], "value": 4017.234161275364, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.8768127896336, 702.0347022843594, 732.3445516262944, 780.9726659566915, 782.7244372926475, 13930.233837138954, 14471.710565788679, 14596.41614335428, 14746.705065898675, 14808.851911480895, 13833.23917184557, 13865.861341386608, 14003.299835008234, 14015.159090454554, 14050.0, 620.0822782282225, 879.3756432932626, 880.6433394475248, 881.6120906801019, 884.0364003236471, 638.7732355013973, 887.8401887660218, 902.702108304258, 906.714384968736, 907.6324088744069, 941.2281928818371, 941.7363138321281, 943.0, 943.32552225159, 943.4622265308777, 946.9914541013799, 947.1049857884302, 947.4504787223394, 948.1182500274741, 948.4214629076258, 693.6670398208837, 693.9340762627593, 694.4513834071078, 694.9826254343632, 695.478391206595, 14804.777928331083, 14825.18461484618, 14880.702385952329, 14942.701145977127, 15043.548693539211, 13951.441942322295, 14128.364223609962, 14145.646358841008, 14149.575512734635, 14227.35976881043, 11385.943070284678, 11433.428328583537, 11455.026322762575, 11553.13351498641, 11992.811501597462, 3669.5779985301765, 3680.705543556509, 3688.723345749053, 3770.057485628593, 3847.403652433877, 93381.39351187639, 95632.9999999993, 95681.56477652841, 96683.03316966881, 97303.0, 10781.568737250422, 10804.459777011276, 10811.729706757395, 11064.834027489595, 11269.436528173559, 21441.46396340104, 22227.110915563197, 22307.442313941956, 22997.540049198924, 23046.0, 7568.013682284566, 7642.426817988673, 7700.345470634947, 7710.845783084307, 7858.332041776399, 4033.878983630496, 4105.577689243028, 4127.938080928789, 4195.433616461739, 4312.5903039211225, 3513.7364697647777, 3586.8744593939086, 3613.6205698401814, 3631.1829838286176, 3641.817909104534, 658.5653468710628, 667.1327274543074, 674.3189378727423, 675.8546912413855, 681.4922882452605, 638.4636904999822, 644.7292137302251, 648.2545073165898, 676.2459857259219, 684.8116767888871, 8383.748487545383, 9597.040295970348, 9712.368696034877, 9863.457509836884, 9901.504924753734, 614.7848253111465, 662.8309781005813, 675.8715843989626, 678.4063944048958, 681.4718593090296, 4299.279144916729, 4841.072870256886, 4850.442199147073, 4902.0587647411985, 4907.114968540975, 78878.0, 94638.0, 96139.0, 96183.0, 96562.51718741361, 80616.77292400757, 83601.0, 84328.15671843325, 85760.0, 86554.0, 74415.0, 74584.25415745881, 75779.62110189414, 76414.23585764071, 76830.61584692153, 9068.36521443519, 9089.636414543344, 9128.178463938277, 9154.908450915405, 9219.985801561688, 39062.21875562416, 43520.69437916868, 46785.362512311905, 46947.0, 48713.0, 53330.40008799742, 55754.44245557493, 59803.70098149481, 60438.75301773955, 61171.01310179035, 10125.89874101279, 10439.164866810484, 11249.550017999189, 11601.941990289995, 11900.869417405333, 12073.758524829278, 12095.879041209475, 12145.696357590778, 12259.632211033684, 12367.814482782585, 9537.666181683608, 11581.247218930743, 11584.594539191088, 12122.93938530337, 12616.423393903213, 12823.0, 13102.344882756015, 13207.27359995171, 13393.263370514715, 13394.263315517741, 12469.812952805896, 12558.290641009622, 12608.432620531914, 12763.936180319039, 13187.274699891597, 10631.681049568526, 10656.360618362613, 10731.108128321326, 10912.836307455236, 11053.668389948314, 8760.693375731818, 8831.293496520135, 9498.382605130644, 10064.597416103274, 10446.791064178522], "value": 7217.106668754825, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1511.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 113.0, 113.0, 114.0, 114.0, 115.0, 118.0, 165.0], "value": 119.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 115.0, 118.0, 154.0, 115.0, 107.0, 116.0, 114.0, 135.0, 115.0], "value": 123.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 261.0, 189.0, 186.0, 183.0, 182.0, 183.0, 184.0, 184.0, 184.0], "value": 194.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 61.0, 63.0, 108.0, 87.0, 111.0, 63.0, 60.0, 80.0, 61.0], "value": 77.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 73.0, 109.0, 98.0, 100.0, 74.0, 85.0, 69.0, 68.0, 87.0], "value": 85.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 90.0, 90.0, 91.0, 90.0, 90.0, 90.0, 90.0, 98.0], "value": 91.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 100.0, 97.0, 97.0, 97.0, 98.0, 98.0, 97.0, 96.0], "value": 97.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 107.0, 132.0, 85.0, 87.0, 87.0, 85.0, 88.0, 88.0, 86.0], "value": 93.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 57.0, 59.0, 60.0, 61.0, 60.0, 60.0, 59.0, 61.0], "value": 59.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 69.0, 77.0, 79.0, 75.0, 70.0, 69.0, 70.0, 71.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 97.0, 93.0, 96.0, 94.0, 97.0, 95.0, 95.0, 99.0], "value": 94.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 146.0, 138.0, 138.0, 164.0, 155.0, 152.0, 188.0, 145.0], "value": 152.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 184.0, 183.0, 183.0, 184.0, 180.0, 183.0, 181.0, 188.0], "value": 183.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 69.0, 70.0, 68.0, 67.0, 68.0, 69.0, 69.0, 67.0], "value": 68.6, "unit": "score"}]}]} 09:27:28 INFO - Return code: 0 09:27:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:27:28 ERROR - # TBPL SUCCESS # 09:27:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:27:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:27:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:27:28 INFO - 2017-02-14 09:27:28.113 screenresolution[1649:10306] starting screenresolution argv=screenresolution get 09:27:28 INFO - 2017-02-14 09:27:28.116 screenresolution[1649:10306] Display 0: 1600x1200x32@60 09:27:28 INFO - 2017-02-14 09:27:28.122 screenresolution[1650:10309] starting screenresolution argv=screenresolution list 09:27:28 INFO - Available Modes on Display 0 09:27:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:27:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:27:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:27:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:27:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:27:28 INFO - Intel Iris: 09:27:28 INFO - Chipset Model: Intel Iris 09:27:28 INFO - Type: GPU 09:27:28 INFO - Bus: Built-In 09:27:28 INFO - VRAM (Dynamic, Max): 1536 MB 09:27:28 INFO - Vendor: Intel (0x8086) 09:27:28 INFO - Device ID: 0x0a2e 09:27:28 INFO - Revision ID: 0x0009 09:27:28 INFO - Displays: 09:27:28 INFO - Display: 09:27:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:27:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:27:28 INFO - Display Serial Number: 1600x1200 60 09:27:28 INFO - Main Display: Yes 09:27:28 INFO - Mirror: Off 09:27:28 INFO - Online: Yes 09:27:28 INFO - Rotation: Supported 09:27:28 INFO - Return code: 0 09:27:28 INFO - Running post-action listener: _resource_record_post_action 09:27:28 INFO - [mozharness: 2017-02-14 17:27:28.358914Z] Finished run-tests step (success) 09:27:28 INFO - Running post-run listener: _resource_record_post_run 09:27:28 INFO - Total resource usage - Wall time: 1205s; CPU: 54.0%; Read bytes: 37843968; Write bytes: 552932352; Read time: 236; Write time: 1645 09:27:28 INFO - TinderboxPrint: CPU usage
53.8% 09:27:28 INFO - TinderboxPrint: I/O read bytes / time
37,843,968 / 236 09:27:28 INFO - TinderboxPrint: I/O write bytes / time
552,932,352 / 1,645 09:27:28 INFO - TinderboxPrint: CPU idle
2,225.3 (46.2%) 09:27:28 INFO - TinderboxPrint: CPU system
959.6 (19.9%) 09:27:28 INFO - TinderboxPrint: CPU user
1,636.8 (33.9%) 09:27:28 INFO - TinderboxPrint: Swap in / out
290,095,104 / 0 09:27:28 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 234387968; Write bytes: 228251648; Read time: 17212; Write time: 483 09:27:28 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 36897792; Write bytes: 297782272; Read time: 223; Write time: 1093 09:27:28 INFO - Running post-run listener: _upload_blobber_files 09:27:28 INFO - Blob upload gear active. 09:27:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:27:28 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:27:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:27:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:27:29 INFO - (blobuploader) - INFO - Open directory for files ... 09:27:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:27:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:27:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:30 INFO - (blobuploader) - INFO - Done attempting. 09:27:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:27:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:27:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:31 INFO - (blobuploader) - INFO - Done attempting. 09:27:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:27:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:27:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:32 INFO - (blobuploader) - INFO - Done attempting. 09:27:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:27:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:27:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:34 INFO - (blobuploader) - INFO - Done attempting. 09:27:34 INFO - (blobuploader) - INFO - Iteration through files over. 09:27:34 INFO - Return code: 0 09:27:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:27:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:27:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab99182b274f9047ed22d6fc83928d9038342c8c77108bf9814e4d18e407c5a8045084f2c544cafac00fff1072ea74af603de1921973632406498ee1e36f9302", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0804fb0b3e3554cca5fad33b6dc91e1ff2afd0960e74640e8983bc11775ef593c90a2b9493a95dfd1865e28541ce77debf8af91be9a12c5d425064dcf6327e29", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf933c656c1c6ea5f7dcb1ba29a4087c04619c0ad3cc9bbf68bfb0f4ae2cce3ff8f6f327d2b0a92eeb2923f7a48d6316b46a2cf45f3850034e12b806bbbde40e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b3b6105506ee09896ed04ccd97c675af2c7365dab96f8051ce0ebf1d5919c179ca93cb927191ba2d08c39169c44b5d88e2d85f4870f48705f847416828ecfd12"} 09:27:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:27:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:27:34 INFO - Contents: 09:27:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab99182b274f9047ed22d6fc83928d9038342c8c77108bf9814e4d18e407c5a8045084f2c544cafac00fff1072ea74af603de1921973632406498ee1e36f9302", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0804fb0b3e3554cca5fad33b6dc91e1ff2afd0960e74640e8983bc11775ef593c90a2b9493a95dfd1865e28541ce77debf8af91be9a12c5d425064dcf6327e29", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf933c656c1c6ea5f7dcb1ba29a4087c04619c0ad3cc9bbf68bfb0f4ae2cce3ff8f6f327d2b0a92eeb2923f7a48d6316b46a2cf45f3850034e12b806bbbde40e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b3b6105506ee09896ed04ccd97c675af2c7365dab96f8051ce0ebf1d5919c179ca93cb927191ba2d08c39169c44b5d88e2d85f4870f48705f847416828ecfd12"} 09:27:34 INFO - Running post-run listener: copy_logs_to_upload_dir 09:27:34 INFO - Copying logs to upload dir... 09:27:34 INFO - mkdir: /builds/slave/test/build/upload/logs 09:27:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.960973 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-02-14 09:27:34.781114) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-14 09:27:34.785022) ========= 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.fJCrKfekoq/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.p6ECmNrNW2/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab99182b274f9047ed22d6fc83928d9038342c8c77108bf9814e4d18e407c5a8045084f2c544cafac00fff1072ea74af603de1921973632406498ee1e36f9302", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0804fb0b3e3554cca5fad33b6dc91e1ff2afd0960e74640e8983bc11775ef593c90a2b9493a95dfd1865e28541ce77debf8af91be9a12c5d425064dcf6327e29", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf933c656c1c6ea5f7dcb1ba29a4087c04619c0ad3cc9bbf68bfb0f4ae2cce3ff8f6f327d2b0a92eeb2923f7a48d6316b46a2cf45f3850034e12b806bbbde40e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b3b6105506ee09896ed04ccd97c675af2c7365dab96f8051ce0ebf1d5919c179ca93cb927191ba2d08c39169c44b5d88e2d85f4870f48705f847416828ecfd12"} build_url:https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011377 build_url: 'https://queue.taskcluster.net/v1/task/A_37H-mVSJKlBMyTpsPAaQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab99182b274f9047ed22d6fc83928d9038342c8c77108bf9814e4d18e407c5a8045084f2c544cafac00fff1072ea74af603de1921973632406498ee1e36f9302", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0804fb0b3e3554cca5fad33b6dc91e1ff2afd0960e74640e8983bc11775ef593c90a2b9493a95dfd1865e28541ce77debf8af91be9a12c5d425064dcf6327e29", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf933c656c1c6ea5f7dcb1ba29a4087c04619c0ad3cc9bbf68bfb0f4ae2cce3ff8f6f327d2b0a92eeb2923f7a48d6316b46a2cf45f3850034e12b806bbbde40e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b3b6105506ee09896ed04ccd97c675af2c7365dab96f8051ce0ebf1d5919c179ca93cb927191ba2d08c39169c44b5d88e2d85f4870f48705f847416828ecfd12"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-14 09:27:34.824638) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 09:27:34.824979) ========= 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.fJCrKfekoq/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.p6ECmNrNW2/Listeners TMPDIR=/var/folders/d7/7bqvmyhs3jdby27d06bbj7dh00000w/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.005051 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 09:27:34.876606) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-14 09:27:34.877327) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-14 09:27:34.877963) ========= ========= Total master_lag: 0.88 =========