builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0093 starttime: 1489539809.64 results: success (0) buildid: 20170314164336 builduid: 0b1bc1af44f9429aadf59f8ea3fb097d revision: fd8bdabb4813164e70b51d02b420e3659eb38536 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:29.639655) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:29.640169) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:29.640468) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:29.668307) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:29.668771) ========= 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.pEWu1u96Iy/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.hh9EEjaB4u/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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-03-14 18:03:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.26M=0.001s 2017-03-14 18:03:29 (9.26 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.093661 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:29.787280) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:29.787659) ========= 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.pEWu1u96Iy/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.hh9EEjaB4u/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.027985 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:29.844039) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-14 18:03:29.844374) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fd8bdabb4813164e70b51d02b420e3659eb38536 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fd8bdabb4813164e70b51d02b420e3659eb38536 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pEWu1u96Iy/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.hh9EEjaB4u/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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-03-14 18:03:29,947 truncating revision to first 12 chars 2017-03-14 18:03:29,947 Setting DEBUG logging. 2017-03-14 18:03:29,947 attempt 1/10 2017-03-14 18:03:29,947 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fd8bdabb4813?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-14 18:03:31,823 unpacking tar archive at: mozilla-aurora-fd8bdabb4813/testing/mozharness/ program finished with exit code 0 elapsedTime=2.149511 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-14 18:03:32.017509) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:32.017821) ========= script_repo_revision: fd8bdabb4813164e70b51d02b420e3659eb38536 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:32.018221) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:32.018582) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-14 18:03:32.038896) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 55 secs) (at 2017-03-14 18:03:32.039306) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pEWu1u96Iy/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.hh9EEjaB4u/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 18:03:32 INFO - MultiFileLogger online at 20170314 18:03:32 in /builds/slave/test 18:03:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:03:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:03:32 INFO - {'append_to_log': False, 18:03:32 INFO - 'base_work_dir': '/builds/slave/test', 18:03:32 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:03:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:03:32 INFO - 'branch': 'Mozilla-Aurora', 18:03:32 INFO - 'buildbot_json_path': 'buildprops.json', 18:03:32 INFO - 'config_files': ('talos/mac_config.py',), 18:03:32 INFO - 'default_actions': ('clobber', 18:03:32 INFO - 'read-buildbot-config', 18:03:32 INFO - 'download-and-extract', 18:03:32 INFO - 'populate-webroot', 18:03:32 INFO - 'create-virtualenv', 18:03:32 INFO - 'install', 18:03:32 INFO - 'run-tests'), 18:03:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:03:32 INFO - 'download_minidump_stackwalk': True, 18:03:32 INFO - 'download_symbols': 'ondemand', 18:03:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:03:32 INFO - 'tooltool.py': '/tools/tooltool.py', 18:03:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:03:32 INFO - '/tools/misc-python/virtualenv.py')}, 18:03:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:03:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:03:32 INFO - 'gecko_profile': False, 18:03:32 INFO - 'gecko_profile_interval': 0, 18:03:32 INFO - 'installer_path': 'installer.exe', 18:03:32 INFO - 'log_level': 'info', 18:03:32 INFO - 'log_name': 'talos', 18:03:32 INFO - 'log_to_console': True, 18:03:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:03:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:03:32 INFO - 'opt_config_files': (), 18:03:32 INFO - 'pip_index': False, 18:03:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:03:32 INFO - 'cmd': ('bash', 18:03:32 INFO - '-c', 18:03:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:03:32 INFO - 'enabled': True, 18:03:32 INFO - 'halt_on_failure': False, 18:03:32 INFO - 'name': 'check_screen_resolution'},), 18:03:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:03:32 INFO - 'cmd': ('bash', 18:03:32 INFO - '-c', 18:03:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:03:32 INFO - 'enabled': True, 18:03:32 INFO - 'halt_on_failure': False, 18:03:32 INFO - 'name': 'check_screen_resolution'},), 18:03:32 INFO - 'run_cmd_checks_enabled': True, 18:03:32 INFO - 'suite': 'dromaeojs', 18:03:32 INFO - 'system_bits': '32', 18:03:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:03:32 INFO - 'title': 't-yosemite-r7-0093', 18:03:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:03:32 INFO - 'use_talos_json': True, 18:03:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:03:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:03:32 INFO - 'work_dir': 'build'} 18:03:32 INFO - [mozharness: 2017-03-15 01:03:32.258275Z] Running clobber step. 18:03:32 INFO - Running pre-action listener: _resource_record_pre_action 18:03:32 INFO - Running main action method: clobber 18:03:32 INFO - rmtree: /builds/slave/test/build 18:03:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:03:34 INFO - Running post-action listener: _resource_record_post_action 18:03:34 INFO - [mozharness: 2017-03-15 01:03:34.759843Z] Finished clobber step (success) 18:03:34 INFO - [mozharness: 2017-03-15 01:03:34.759968Z] Running read-buildbot-config step. 18:03:34 INFO - Running pre-action listener: _resource_record_pre_action 18:03:34 INFO - Running main action method: read_buildbot_config 18:03:34 INFO - Using buildbot properties: 18:03:34 INFO - { 18:03:34 INFO - "project": "", 18:03:34 INFO - "product": "firefox", 18:03:34 INFO - "script_repo_revision": "production", 18:03:34 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 18:03:34 INFO - "repository": "", 18:03:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 18:03:34 INFO - "buildid": "20170314164336", 18:03:34 INFO - "pgo_build": "False", 18:03:34 INFO - "basedir": "/builds/slave/test", 18:03:34 INFO - "buildnumber": 90, 18:03:34 INFO - "slavename": "t-yosemite-r7-0093", 18:03:34 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 18:03:34 INFO - "platform": "yosemite_r7", 18:03:34 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:03:34 INFO - "branch": "mozilla-aurora", 18:03:34 INFO - "repo_path": "releases/mozilla-aurora", 18:03:34 INFO - "revision": "fd8bdabb4813164e70b51d02b420e3659eb38536", 18:03:34 INFO - "stage_platform": "macosx64", 18:03:34 INFO - "builduid": "0b1bc1af44f9429aadf59f8ea3fb097d", 18:03:34 INFO - "slavebuilddir": "test" 18:03:34 INFO - } 18:03:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 18:03:34 INFO - Running post-action listener: _resource_record_post_action 18:03:34 INFO - [mozharness: 2017-03-15 01:03:34.763481Z] Finished read-buildbot-config step (success) 18:03:34 INFO - [mozharness: 2017-03-15 01:03:34.763591Z] Running download-and-extract step. 18:03:34 INFO - Running pre-action listener: _resource_record_pre_action 18:03:34 INFO - Running main action method: download_and_extract 18:03:34 INFO - mkdir: /builds/slave/test/build/tests 18:03:34 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')]} 18:03:34 INFO - trying https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 18:03:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 18:03:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 18:03:36 INFO - Downloaded 1199 bytes. 18:03:36 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 18:03:36 INFO - Using the following test package requirements: 18:03:36 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 18:03:36 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:03:36 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 18:03:36 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:03:36 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 18:03:36 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:03:36 INFO - u'jsshell-mac.zip'], 18:03:36 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:03:36 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 18:03:36 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 18:03:36 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:03:36 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 18:03:36 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:03:36 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 18:03:36 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:03:36 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 18:03:36 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:03:36 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 18:03:36 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:03:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 18:03:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:03:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 18:03:37 INFO - Expected file size: 51780715 18:03:37 INFO - Obtained file size: 51780715 18:03:53 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 18:03:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 18:03:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:03:53 INFO - Fetch https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 18:04:25 INFO - retry: attempt #1 caught exception: The read operation timed out 18:04:25 INFO - retry: Failed, sleeping 60 seconds before retrying 18:05:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #2 18:05:25 INFO - Fetch https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 18:05:27 INFO - Expected file size: 51780715 18:05:27 INFO - Obtained file size: 51780715 18:05:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 18:05:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 18:05:42 INFO - Fetch https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 18:05:43 INFO - Expected file size: 14404424 18:05:43 INFO - Obtained file size: 14404424 18:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:44 INFO - trying https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 18:05:44 INFO - Downloading https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 18:05:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 18:05:46 INFO - Downloaded 74627176 bytes. 18:05:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 18:05:46 INFO - mkdir: /builds/slave/test/properties 18:05:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:05:46 INFO - Writing to file /builds/slave/test/properties/build_url 18:05:46 INFO - Contents: 18:05:46 INFO - build_url:https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 18:05:46 INFO - Running post-action listener: _resource_record_post_action 18:05:46 INFO - Running post-action listener: set_extra_try_arguments 18:05:46 INFO - [mozharness: 2017-03-15 01:05:46.971663Z] Finished download-and-extract step (success) 18:05:46 INFO - [mozharness: 2017-03-15 01:05:46.971818Z] Running populate-webroot step. 18:05:46 INFO - Running pre-action listener: _resource_record_pre_action 18:05:46 INFO - Running main action method: populate_webroot 18:05:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:05:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:05:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:05:46 INFO - '--tppagecycles', 18:05:46 INFO - '7'], 18:05:46 INFO - 'tests': ['tsvgm']}}, 18:05:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:05:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:05:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:05:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:05:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:05:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:05:46 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:05:46 INFO - 'talos_options': ['--disable-e10s'], 18:05:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:05:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:05:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:05:46 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:05:46 INFO - 'talos_options': ['--disable-e10s'], 18:05:46 INFO - 'tests': ['damp', 'tps']}, 18:05:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:05:46 INFO - 'tests': ['damp', 'tps']}, 18:05:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:05:46 INFO - 'tests': ['dromaeo_dom']}, 18:05:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:05:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:05:46 INFO - 'tests': ['basic_compositor_video']}, 18:05:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:05:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:05:46 INFO - 'tests': ['a11yr', 18:05:46 INFO - 'ts_paint', 18:05:46 INFO - 'tpaint', 18:05:46 INFO - 'sessionrestore', 18:05:46 INFO - 'sessionrestore_no_auto_restore', 18:05:46 INFO - 'tabpaint']}, 18:05:46 INFO - 'other-e10s': {'tests': ['a11yr', 18:05:46 INFO - 'ts_paint', 18:05:46 INFO - 'tpaint', 18:05:46 INFO - 'sessionrestore', 18:05:46 INFO - 'sessionrestore_no_auto_restore', 18:05:46 INFO - 'tabpaint']}, 18:05:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:05:46 INFO - 'ts_paint', 18:05:46 INFO - 'tpaint', 18:05:46 INFO - 'sessionrestore', 18:05:46 INFO - 'sessionrestore_no_auto_restore', 18:05:46 INFO - 'tabpaint']}, 18:05:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:05:46 INFO - 'ts_paint', 18:05:46 INFO - 'tpaint', 18:05:46 INFO - 'sessionrestore', 18:05:46 INFO - 'sessionrestore_no_auto_restore', 18:05:46 INFO - 'tabpaint']}, 18:05:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:05:46 INFO - 'tests': ['a11yr', 18:05:46 INFO - 'ts_paint', 18:05:46 INFO - 'tpaint', 18:05:46 INFO - 'sessionrestore', 18:05:46 INFO - 'sessionrestore_no_auto_restore', 18:05:46 INFO - 'tabpaint']}, 18:05:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:05:46 INFO - 'tests': ['a11yr', 18:05:46 INFO - 'ts_paint', 18:05:46 INFO - 'tpaint', 18:05:46 INFO - 'sessionrestore', 18:05:46 INFO - 'sessionrestore_no_auto_restore', 18:05:46 INFO - 'tabpaint']}, 18:05:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:05:46 INFO - 'tests': ['tsvgx', 18:05:46 INFO - 'tsvgr_opacity', 18:05:46 INFO - 'tart', 18:05:46 INFO - 'tscrollx', 18:05:46 INFO - 'cart', 18:05:46 INFO - 'tsvg_static']}, 18:05:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:05:46 INFO - 'tsvgr_opacity', 18:05:46 INFO - 'tart', 18:05:46 INFO - 'tscrollx', 18:05:46 INFO - 'cart', 18:05:46 INFO - 'tsvg_static']}, 18:05:46 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:05:46 INFO - 'talos_options': ['--disable-e10s'], 18:05:46 INFO - 'tests': ['tp5o']}, 18:05:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:05:46 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:05:46 INFO - 'talos_options': ['--disable-e10s', 18:05:46 INFO - '--xperf_path', 18:05:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:05:46 INFO - 'tests': ['tp5n']}, 18:05:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:05:46 INFO - 'talos_options': ['--xperf_path', 18:05:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:05:46 INFO - 'tests': ['tp5n']}}, 18:05:46 INFO - 'talos.zip': {'path': '', 18:05:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:05:46 INFO - Running post-action listener: _resource_record_post_action 18:05:46 INFO - [mozharness: 2017-03-15 01:05:46.990181Z] Finished populate-webroot step (success) 18:05:46 INFO - [mozharness: 2017-03-15 01:05:46.990327Z] Running create-virtualenv step. 18:05:46 INFO - Running pre-action listener: _resource_record_pre_action 18:05:46 INFO - Running main action method: create_virtualenv 18:05:46 INFO - Creating virtualenv /builds/slave/test/build/venv 18:05:46 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 18:05:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:05:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:05:47 INFO - Using real prefix '/tools/python27' 18:05:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:05:47 INFO - Installing distribute.............................................................................................................................................................................................done. 18:05:50 INFO - Installing pip.................done. 18:05:50 INFO - Return code: 0 18:05:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:05:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:05:50 INFO - Reading from file tmpfile_stdout 18:05:50 INFO - Output received: 18:05:50 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) 18:05:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:05:50 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')]} 18:05:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:50 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')]} 18:05:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:50 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 0x10ac35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee2bdd9ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa13d30>, '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 0x10a93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa08be8>, '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.pEWu1u96Iy/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.hh9EEjaB4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 18:05:50 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 18:05:50 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 18:05:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pEWu1u96Iy/Render', 18:05:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:50 INFO - 'HOME': '/Users/cltbld', 18:05:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:50 INFO - 'LOGNAME': 'cltbld', 18:05:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:50 INFO - 'MOZ_NO_REMOTE': '1', 18:05:50 INFO - 'NO_EM_RESTART': '1', 18:05:50 INFO - 'PAGER': '/bin/cat', 18:05:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:50 INFO - 'PWD': '/builds/slave/test', 18:05:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:50 INFO - 'SHELL': '/bin/bash', 18:05:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hh9EEjaB4u/Listeners', 18:05:50 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 18:05:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:50 INFO - 'USER': 'cltbld', 18:05:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:50 INFO - 'XPC_FLAGS': '0x0', 18:05:50 INFO - 'XPC_SERVICE_NAME': '0', 18:05:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:51 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 18:05:51 INFO - Cleaning up... 18:05:51 INFO - Return code: 0 18:05:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:05:51 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')]} 18:05:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:51 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')]} 18:05:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:51 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 0x10ac35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee2bdd9ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa13d30>, '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 0x10a93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa08be8>, '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.pEWu1u96Iy/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.hh9EEjaB4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 18:05:51 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 18:05:51 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 18:05:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pEWu1u96Iy/Render', 18:05:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:51 INFO - 'HOME': '/Users/cltbld', 18:05:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:51 INFO - 'LOGNAME': 'cltbld', 18:05:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:51 INFO - 'MOZ_NO_REMOTE': '1', 18:05:51 INFO - 'NO_EM_RESTART': '1', 18:05:51 INFO - 'PAGER': '/bin/cat', 18:05:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:51 INFO - 'PWD': '/builds/slave/test', 18:05:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:51 INFO - 'SHELL': '/bin/bash', 18:05:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hh9EEjaB4u/Listeners', 18:05:51 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 18:05:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:51 INFO - 'USER': 'cltbld', 18:05:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:51 INFO - 'XPC_FLAGS': '0x0', 18:05:51 INFO - 'XPC_SERVICE_NAME': '0', 18:05:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:51 INFO - Downloading/unpacking psutil>=3.1.1 18:05:51 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 18:05:51 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 18:05:51 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 18:05:51 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 18:05:51 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 18:05:51 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 18:05:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:05:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:05:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:05:55 INFO - Installing collected packages: psutil 18:05:55 INFO - Running setup.py install for psutil 18:05:55 INFO - building 'psutil._psutil_osx' extension 18:05:55 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 18:05:55 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 18:05:56 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 18:05:56 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 18:05:56 INFO - building 'psutil._psutil_posix' extension 18:05:56 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 18:05:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:05:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:05:56 INFO - ^ 18:05:56 INFO - 1 warning generated. 18:05:56 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 18:05:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:05:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:05:56 INFO - Successfully installed psutil 18:05:56 INFO - Cleaning up... 18:05:56 INFO - Return code: 0 18:05:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:05:56 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')]} 18:05:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:56 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')]} 18:05:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:56 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 0x10ac35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee2bdd9ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa13d30>, '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 0x10a93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa08be8>, '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.pEWu1u96Iy/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.hh9EEjaB4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 18:05:56 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 18:05:56 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 18:05:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pEWu1u96Iy/Render', 18:05:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:56 INFO - 'HOME': '/Users/cltbld', 18:05:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:56 INFO - 'LOGNAME': 'cltbld', 18:05:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:56 INFO - 'MOZ_NO_REMOTE': '1', 18:05:56 INFO - 'NO_EM_RESTART': '1', 18:05:56 INFO - 'PAGER': '/bin/cat', 18:05:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:56 INFO - 'PWD': '/builds/slave/test', 18:05:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:56 INFO - 'SHELL': '/bin/bash', 18:05:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hh9EEjaB4u/Listeners', 18:05:56 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 18:05:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:56 INFO - 'USER': 'cltbld', 18:05:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:56 INFO - 'XPC_FLAGS': '0x0', 18:05:56 INFO - 'XPC_SERVICE_NAME': '0', 18:05:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:05:56 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 18:05:56 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 18:05:56 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 18:05:56 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 18:05:56 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 18:05:56 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 18:05:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:05:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:05:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:05:59 INFO - Installing collected packages: mozsystemmonitor 18:05:59 INFO - Running setup.py install for mozsystemmonitor 18:05:59 INFO - Successfully installed mozsystemmonitor 18:05:59 INFO - Cleaning up... 18:05:59 INFO - Return code: 0 18:05:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:05:59 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')]} 18:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:59 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')]} 18:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:59 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 0x10ac35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee2bdd9ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa13d30>, '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 0x10a93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa08be8>, '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.pEWu1u96Iy/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.hh9EEjaB4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 18:05:59 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 18:05:59 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 18:05:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pEWu1u96Iy/Render', 18:05:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:59 INFO - 'HOME': '/Users/cltbld', 18:05:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:59 INFO - 'LOGNAME': 'cltbld', 18:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:59 INFO - 'MOZ_NO_REMOTE': '1', 18:05:59 INFO - 'NO_EM_RESTART': '1', 18:05:59 INFO - 'PAGER': '/bin/cat', 18:05:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:59 INFO - 'PWD': '/builds/slave/test', 18:05:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:59 INFO - 'SHELL': '/bin/bash', 18:05:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hh9EEjaB4u/Listeners', 18:05:59 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 18:05:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:59 INFO - 'USER': 'cltbld', 18:05:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:59 INFO - 'XPC_FLAGS': '0x0', 18:05:59 INFO - 'XPC_SERVICE_NAME': '0', 18:05:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:59 INFO - Downloading/unpacking jsonschema==2.5.1 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:06:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:06:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:06:02 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 18:06:02 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 18:06:02 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 18:06:02 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 18:06:02 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 18:06:02 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 18:06:02 INFO - Downloading functools32-3.2.3-2.tar.gz 18:06:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:06:03 INFO - warning: no files found matching '*.txt' 18:06:03 INFO - no previously-included directories found matching 'build' 18:06:03 INFO - no previously-included directories found matching 'dist' 18:06:03 INFO - no previously-included directories found matching '.git*' 18:06:03 INFO - Installing collected packages: jsonschema, functools32 18:06:03 INFO - Running setup.py install for functools32 18:06:03 INFO - warning: no files found matching '*.txt' 18:06:03 INFO - no previously-included directories found matching 'build' 18:06:03 INFO - no previously-included directories found matching 'dist' 18:06:03 INFO - no previously-included directories found matching '.git*' 18:06:03 INFO - Successfully installed jsonschema functools32 18:06:03 INFO - Cleaning up... 18:06:03 INFO - Return code: 0 18:06:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:06:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:03 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 0x10ac35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee2bdd9ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa13d30>, '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 0x10a93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa08be8>, '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.pEWu1u96Iy/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.hh9EEjaB4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 18:06:03 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 18:06:03 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 18:06:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pEWu1u96Iy/Render', 18:06:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:06:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:06:03 INFO - 'HOME': '/Users/cltbld', 18:06:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:06:03 INFO - 'LOGNAME': 'cltbld', 18:06:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:03 INFO - 'MOZ_NO_REMOTE': '1', 18:06:03 INFO - 'NO_EM_RESTART': '1', 18:06:03 INFO - 'PAGER': '/bin/cat', 18:06:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:06:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:03 INFO - 'PWD': '/builds/slave/test', 18:06:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:06:03 INFO - 'SHELL': '/bin/bash', 18:06:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hh9EEjaB4u/Listeners', 18:06:03 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 18:06:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:06:03 INFO - 'USER': 'cltbld', 18:06:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:06:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:06:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:03 INFO - 'XPC_FLAGS': '0x0', 18:06:03 INFO - 'XPC_SERVICE_NAME': '0', 18:06:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:06:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:06:03 INFO - Cleaning up... 18:06:03 INFO - Return code: 0 18:06:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:06:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:03 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 0x10ac35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee2bdd9ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa13d30>, '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 0x10a93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa08be8>, '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.pEWu1u96Iy/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.hh9EEjaB4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 18:06:03 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 18:06:03 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 18:06:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pEWu1u96Iy/Render', 18:06:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:06:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:06:03 INFO - 'HOME': '/Users/cltbld', 18:06:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:06:03 INFO - 'LOGNAME': 'cltbld', 18:06:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:03 INFO - 'MOZ_NO_REMOTE': '1', 18:06:03 INFO - 'NO_EM_RESTART': '1', 18:06:03 INFO - 'PAGER': '/bin/cat', 18:06:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:06:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:03 INFO - 'PWD': '/builds/slave/test', 18:06:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:06:03 INFO - 'SHELL': '/bin/bash', 18:06:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hh9EEjaB4u/Listeners', 18:06:03 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 18:06:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:06:03 INFO - 'USER': 'cltbld', 18:06:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:06:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:06:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:03 INFO - 'XPC_FLAGS': '0x0', 18:06:03 INFO - 'XPC_SERVICE_NAME': '0', 18:06:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:06:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:03 INFO - Downloading/unpacking blobuploader==1.2.4 18:06: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 18:06: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 18:06: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 18:06: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 18:06: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 18:06: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 18:06:06 INFO - Downloading blobuploader-1.2.4.tar.gz 18:06:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:06:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:06:06 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 18:06:06 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 18:06:06 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 18:06:06 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 18:06:06 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 18:06:06 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 18:06:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:06:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:06: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 18:06: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 18:06: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 18:06: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 18:06: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 18:06: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 18:06:07 INFO - Downloading docopt-0.6.1.tar.gz 18:06:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:06:07 INFO - Installing collected packages: blobuploader, requests, docopt 18:06:07 INFO - Running setup.py install for blobuploader 18:06:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:06:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:06:08 INFO - Running setup.py install for requests 18:06:08 INFO - Running setup.py install for docopt 18:06:08 INFO - Successfully installed blobuploader requests docopt 18:06:08 INFO - Cleaning up... 18:06:08 INFO - Return code: 0 18:06:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:06:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:08 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 0x10ac35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee2bdd9ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa13d30>, '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 0x10a93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa08be8>, '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.pEWu1u96Iy/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.hh9EEjaB4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 18:06:08 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 18:06:08 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 18:06:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pEWu1u96Iy/Render', 18:06:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:06:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:06:08 INFO - 'HOME': '/Users/cltbld', 18:06:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:06:08 INFO - 'LOGNAME': 'cltbld', 18:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:08 INFO - 'MOZ_NO_REMOTE': '1', 18:06:08 INFO - 'NO_EM_RESTART': '1', 18:06:08 INFO - 'PAGER': '/bin/cat', 18:06:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:08 INFO - 'PWD': '/builds/slave/test', 18:06:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:06:08 INFO - 'SHELL': '/bin/bash', 18:06:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hh9EEjaB4u/Listeners', 18:06:08 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 18:06:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:06:08 INFO - 'USER': 'cltbld', 18:06:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:06:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:08 INFO - 'XPC_FLAGS': '0x0', 18:06:08 INFO - 'XPC_SERVICE_NAME': '0', 18:06:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:06:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:06:09 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-wp0yjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:06:09 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-p3f_vP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:06:09 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-HwNwGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:06:09 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-r_ZGKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:06:09 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-FU0cak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:06:09 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-n5Kj7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:06:09 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-0VLTmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:06:09 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-HvH1um-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:06:09 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-JCpr6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:06:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:06:09 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-Ydo21a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:06:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-Wz18SJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:06:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-6tlDvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:06:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-0w3j0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:06:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-X44TMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:06:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-7vHdtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:06:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-xT3EQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:06:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:06:10 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-2ub5mu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:06:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:06:10 INFO - Running setup.py install for manifestparser 18:06:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:06:10 INFO - Running setup.py install for mozcrash 18:06:11 INFO - Running setup.py install for mozdebug 18:06:11 INFO - Running setup.py install for mozdevice 18:06:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:06:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:06:11 INFO - Running setup.py install for mozfile 18:06:11 INFO - Running setup.py install for mozhttpd 18:06:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:06:11 INFO - Running setup.py install for mozinfo 18:06:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:06:11 INFO - Running setup.py install for mozInstall 18:06:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:06:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:06:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:06:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:06:11 INFO - Running setup.py install for mozleak 18:06:12 INFO - Running setup.py install for mozlog 18:06:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:06:12 INFO - Running setup.py install for moznetwork 18:06:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:06:12 INFO - Running setup.py install for mozprocess 18:06:12 INFO - Running setup.py install for mozprofile 18:06:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:06:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:06:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:06:12 INFO - Running setup.py install for mozrunner 18:06:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:06:12 INFO - Running setup.py install for mozscreenshot 18:06:13 INFO - Running setup.py install for moztest 18:06:13 INFO - Running setup.py install for mozversion 18:06:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:06:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:06:13 INFO - Cleaning up... 18:06:13 INFO - Return code: 0 18:06:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:06:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:06:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:13 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 0x10ac35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee2bdd9ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa13d30>, '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 0x10a93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa08be8>, '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.pEWu1u96Iy/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.hh9EEjaB4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 18:06:13 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 18:06:13 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 18:06:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pEWu1u96Iy/Render', 18:06:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:06:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:06:13 INFO - 'HOME': '/Users/cltbld', 18:06:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:06:13 INFO - 'LOGNAME': 'cltbld', 18:06:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:13 INFO - 'MOZ_NO_REMOTE': '1', 18:06:13 INFO - 'NO_EM_RESTART': '1', 18:06:13 INFO - 'PAGER': '/bin/cat', 18:06:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:06:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:13 INFO - 'PWD': '/builds/slave/test', 18:06:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:06:13 INFO - 'SHELL': '/bin/bash', 18:06:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hh9EEjaB4u/Listeners', 18:06:13 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 18:06:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:06:13 INFO - 'USER': 'cltbld', 18:06:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:06:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:06:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:13 INFO - 'XPC_FLAGS': '0x0', 18:06:13 INFO - 'XPC_SERVICE_NAME': '0', 18:06:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:06:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:06:13 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-nm2vRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:06:13 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)) 18:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:06:13 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-jdbBqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:06:13 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)) 18:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:06:13 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-8yzU1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:06:13 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)) 18:06:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:06:14 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-fFMlgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:06:14 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)) 18:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:06:14 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-k549Fl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:06:14 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)) 18:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:06:14 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-KYTs8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:06:14 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)) 18:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:06:14 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-Lar3gN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:06:14 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)) 18:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:06:14 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-sXoOT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:06:14 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)) 18:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:06:14 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-jwoS6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:06:14 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)) 18:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:06:14 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-qhizNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:06:14 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)) 18:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:06:14 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-lOT0KS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:06:14 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)) 18:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:06:14 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-3YARsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:06:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:06:14 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-MLJoeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:06:15 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)) 18:06:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:06:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-TANWlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:06:15 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)) 18:06:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:06:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-snXBvI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:06:15 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)) 18:06:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:06:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-d4rwTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:06:15 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)) 18:06:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:06:15 INFO - Running setup.py (path:/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/pip-NYqahb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:06:15 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)) 18:06:15 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)) 18:06:15 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)) 18:06:15 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)) 18:06:15 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)) 18:06:15 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)) 18:06:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:06:15 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 18:06:15 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 18:06:15 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 18:06:15 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 18:06:15 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 18:06:15 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 18:06:18 INFO - Downloading blessings-1.6.tar.gz 18:06:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:06:18 INFO - Installing collected packages: blessings 18:06:18 INFO - Running setup.py install for blessings 18:06:18 INFO - Successfully installed blessings 18:06:18 INFO - Cleaning up... 18:06:18 INFO - Return code: 0 18:06:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:06:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:06:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:06:18 INFO - Reading from file tmpfile_stdout 18:06:18 INFO - Current package versions: 18:06:18 INFO - blessings == 1.6 18:06:18 INFO - blobuploader == 1.2.4 18:06:18 INFO - docopt == 0.6.1 18:06:18 INFO - functools32 == 3.2.3-2 18:06:18 INFO - jsonschema == 2.5.1 18:06:18 INFO - manifestparser == 1.1 18:06:18 INFO - mozInstall == 1.12 18:06:18 INFO - mozcrash == 1.0 18:06:18 INFO - mozdebug == 0.1 18:06:18 INFO - mozdevice == 0.49 18:06:18 INFO - mozfile == 1.2 18:06:18 INFO - mozhttpd == 0.7 18:06:18 INFO - mozinfo == 0.9 18:06:18 INFO - mozleak == 0.1 18:06:18 INFO - mozlog == 3.4 18:06:18 INFO - moznetwork == 0.27 18:06:18 INFO - mozprocess == 0.25 18:06:18 INFO - mozprofile == 0.28 18:06:18 INFO - mozrunner == 6.13 18:06:18 INFO - mozscreenshot == 0.1 18:06:18 INFO - mozsystemmonitor == 0.3 18:06:18 INFO - moztest == 0.8 18:06:18 INFO - mozversion == 1.4 18:06:18 INFO - psutil == 3.1.1 18:06:18 INFO - requests == 1.2.3 18:06:18 INFO - wsgiref == 0.1.2 18:06:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:06:18 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')]} 18:06:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:18 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')]} 18:06:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:18 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 0x10ac35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee2bdd9ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa13d30>, '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 0x10a93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa08be8>, '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.pEWu1u96Iy/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.hh9EEjaB4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 18:06:18 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 18:06:18 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 18:06:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pEWu1u96Iy/Render', 18:06:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:06:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:06:18 INFO - 'HOME': '/Users/cltbld', 18:06:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:06:18 INFO - 'LOGNAME': 'cltbld', 18:06:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:18 INFO - 'MOZ_NO_REMOTE': '1', 18:06:18 INFO - 'NO_EM_RESTART': '1', 18:06:18 INFO - 'PAGER': '/bin/cat', 18:06:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:06:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:18 INFO - 'PWD': '/builds/slave/test', 18:06:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:06:18 INFO - 'SHELL': '/bin/bash', 18:06:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hh9EEjaB4u/Listeners', 18:06:18 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 18:06:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:06:18 INFO - 'USER': 'cltbld', 18:06:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:06:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:06:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:18 INFO - 'XPC_FLAGS': '0x0', 18:06:18 INFO - 'XPC_SERVICE_NAME': '0', 18:06:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:06:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:19 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)) 18:06:19 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)) 18:06:19 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)) 18:06:19 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)) 18:06:19 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)) 18:06:19 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)) 18:06:19 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)) 18:06:19 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)) 18:06:19 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)) 18:06:19 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)) 18:06:19 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)) 18:06:19 INFO - Cleaning up... 18:06:19 INFO - Return code: 0 18:06:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:06:19 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')]} 18:06:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:06:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:19 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')]} 18:06:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:06:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:06:19 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 0x10ac35b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee2bdd9ac0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa13d30>, '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 0x10a93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa08be8>, '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.pEWu1u96Iy/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.hh9EEjaB4u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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 18:06:19 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 18:06:19 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 18:06:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pEWu1u96Iy/Render', 18:06:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:06:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:06:19 INFO - 'HOME': '/Users/cltbld', 18:06:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:06:19 INFO - 'LOGNAME': 'cltbld', 18:06:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:19 INFO - 'MOZ_NO_REMOTE': '1', 18:06:19 INFO - 'NO_EM_RESTART': '1', 18:06:19 INFO - 'PAGER': '/bin/cat', 18:06:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:06:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:19 INFO - 'PWD': '/builds/slave/test', 18:06:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:06:19 INFO - 'SHELL': '/bin/bash', 18:06:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hh9EEjaB4u/Listeners', 18:06:19 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 18:06:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:06:19 INFO - 'USER': 'cltbld', 18:06:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:06:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:06:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:19 INFO - 'XPC_FLAGS': '0x0', 18:06:19 INFO - 'XPC_SERVICE_NAME': '0', 18:06:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:06:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:06:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:06:19 INFO - Cleaning up... 18:06:19 INFO - Return code: 0 18:06:19 INFO - Running post-action listener: _resource_record_post_action 18:06:19 INFO - Running post-action listener: _start_resource_monitoring 18:06:19 INFO - Starting resource monitoring. 18:06:19 INFO - [mozharness: 2017-03-15 01:06:19.353824Z] Finished create-virtualenv step (success) 18:06:19 INFO - [mozharness: 2017-03-15 01:06:19.354788Z] Running install step. 18:06:19 INFO - Running pre-action listener: _resource_record_pre_action 18:06:19 INFO - Running main action method: install 18:06:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:06:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:06:19 INFO - Reading from file tmpfile_stdout 18:06:19 INFO - Detecting whether we're running mozinstall >=1.0... 18:06:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:06:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:06:19 INFO - Reading from file tmpfile_stdout 18:06:19 INFO - Output received: 18:06:19 INFO - Usage: mozinstall [options] installer 18:06:19 INFO - Options: 18:06:19 INFO - -h, --help show this help message and exit 18:06:19 INFO - -d DEST, --destination=DEST 18:06:19 INFO - Directory to install application into. [default: 18:06:19 INFO - "/builds/slave/test"] 18:06:19 INFO - --app=APP Application being installed. [default: firefox] 18:06:19 INFO - mkdir: /builds/slave/test/build/application 18:06:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:06:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 18:06:39 INFO - Reading from file tmpfile_stdout 18:06:39 INFO - Output received: 18:06:39 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:06:39 INFO - Running post-action listener: _resource_record_post_action 18:06:39 INFO - [mozharness: 2017-03-15 01:06:39.947444Z] Finished install step (success) 18:06:39 INFO - [mozharness: 2017-03-15 01:06:39.947572Z] Running run-tests step. 18:06:39 INFO - Running pre-action listener: _resource_record_pre_action 18:06:39 INFO - Running main action method: run_tests 18:06:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:06:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:06:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:06:39 INFO - 2017-03-14 18:06:39.961 screenresolution[1628:8027] starting screenresolution argv=screenresolution get 18:06:39 INFO - 2017-03-14 18:06:39.964 screenresolution[1628:8027] Display 0: 1600x1200x32@60 18:06:39 INFO - 2017-03-14 18:06:39.970 screenresolution[1629:8029] starting screenresolution argv=screenresolution list 18:06:39 INFO - Available Modes on Display 0 18:06:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:06:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:06:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:06:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:06:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:06:40 INFO - Intel Iris: 18:06:40 INFO - Chipset Model: Intel Iris 18:06:40 INFO - Type: GPU 18:06:40 INFO - Bus: Built-In 18:06:40 INFO - VRAM (Dynamic, Max): 1536 MB 18:06:40 INFO - Vendor: Intel (0x8086) 18:06:40 INFO - Device ID: 0x0a2e 18:06:40 INFO - Revision ID: 0x0009 18:06:40 INFO - Displays: 18:06:40 INFO - Display: 18:06:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:06:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:06:40 INFO - Display Serial Number: 1600x1200 60 18:06:40 INFO - Main Display: Yes 18:06:40 INFO - Mirror: Off 18:06:40 INFO - Online: Yes 18:06:40 INFO - Rotation: Supported 18:06:40 INFO - Return code: 0 18:06:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:06:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:06:40 INFO - Python 2.7.3 18:06:40 INFO - Return code: 0 18:06:40 INFO - grabbing minidump binary from tooltool 18:06:40 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')]} 18:06:40 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 0x10aa13d30>, '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 0x10a93adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa08be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:06:40 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 18:06:40 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 18:06:40 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 18:06:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:06:40 INFO - Return code: 0 18:06:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:06:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:06:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:06:40 INFO - ENV: RUST_BACKTRACE is now 1 18:06:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:06:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:06:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0093', '--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 18:06:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0093 --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 18:06:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pEWu1u96Iy/Render', 18:06:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:06:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:06:40 INFO - 'HOME': '/Users/cltbld', 18:06:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:06:40 INFO - 'LOGNAME': 'cltbld', 18:06:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:06:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:06:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:40 INFO - 'MOZ_NO_REMOTE': '1', 18:06:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:06:40 INFO - 'NO_EM_RESTART': '1', 18:06:40 INFO - 'PAGER': '/bin/cat', 18:06:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:06:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:40 INFO - 'PWD': '/builds/slave/test', 18:06:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:06:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:06:40 INFO - 'RUST_BACKTRACE': '1', 18:06:40 INFO - 'SHELL': '/bin/bash', 18:06:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hh9EEjaB4u/Listeners', 18:06:40 INFO - 'TMPDIR': '/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/', 18:06:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:06:40 INFO - 'USER': 'cltbld', 18:06:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:06:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:06:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:40 INFO - 'XPC_FLAGS': '0x0', 18:06:40 INFO - 'XPC_SERVICE_NAME': '0', 18:06:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:06:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0093', '--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 18:06:40 INFO - mozversion application_buildid: 20170314164336 18:06:40 INFO - mozversion application_changeset: fd8bdabb4813164e70b51d02b420e3659eb38536 18:06:40 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:06:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:06:40 INFO - mozversion application_name: Firefox 18:06:40 INFO - mozversion application_remotingname: firefox-dev 18:06:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:06:40 INFO - mozversion application_vendor: Mozilla 18:06:40 INFO - mozversion application_version: 54.0a2 18:06:40 INFO - mozversion platform_buildid: 20170314164336 18:06:40 INFO - mozversion platform_changeset: fd8bdabb4813164e70b51d02b420e3659eb38536 18:06:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:06:40 INFO - mozversion platform_version: 54.0a2 18:06:40 INFO - using testdate: 1489540000 18:06:40 INFO - actual date: 1489540000 18:06:40 INFO - starting webserver on 'localhost:49240' 18:06:40 INFO - SUITE-START | Running 2 tests 18:06:40 INFO - TEST-START | dromaeo_css 18:06:40 INFO - operating with platform_type : mac_ 18:06:40 INFO - Initialising browser for dromaeo_css test... 18:06:40 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpzTrWNt/profile http://localhost:49240/getInfo.html) 18:06:43 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 18:06:43 INFO - PROCESS | 1639 | colorDepth:24 18:06:43 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 18:06:43 INFO - PROCESS | 1639 | __metrics 18:06:43 INFO - PROCESS | 1639 | 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" 18:06:43 INFO - PROCESS | 1639 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:06:44 INFO - TEST-INFO | 1639: exit 0 18:06:44 INFO - Browser initialized. 18:06:44 INFO - Running cycle 1/1 for dromaeo_css test... 18:06:44 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpzTrWNt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:06:56 INFO - PROCESS | 1641 | RSS: Main: 215072768 18:06:56 INFO - PROCESS | 1641 | 18:10:00 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 18:10:02 INFO - PROCESS | 1641 | RSS: Main: 398102528 18:10:02 INFO - PROCESS | 1641 | 18:11:45 INFO - PROCESS | 1641 | ************************* 18:11:45 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 18:11:45 INFO - PROCESS | 1641 | 18:11:45 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:11:45 INFO - PROCESS | 1641 | Full stack: 18:11:45 INFO - PROCESS | 1641 | ************************* 18:13:05 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 18:13:06 INFO - PROCESS | 1641 | RSS: Main: 352198656 18:13:06 INFO - PROCESS | 1641 | 18:16:08 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 18:16:09 INFO - PROCESS | 1641 | RSS: Main: 326201344 18:16:09 INFO - PROCESS | 1641 | 18:19:11 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 18:19:12 INFO - PROCESS | 1641 | RSS: Main: 325632000 18:19:12 INFO - PROCESS | 1641 | 18:22:16 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 18:22:16 INFO - PROCESS | 1641 | RSS: Main: 322441216 18:22:16 INFO - PROCESS | 1641 | 18:25:20 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 18:25:20 INFO - PROCESS | 1641 | RSS: Main: 406274048 18:25:20 INFO - PROCESS | 1641 | 18:25:20 INFO - PROCESS | 1641 | __start_tp_report 18:25:20 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 18:25:20 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 18:25:20 INFO - PROCESS | 1641 | |i|pagename|runs| 18:25:20 INFO - PROCESS | 1641 | |0;dojo.html;3392.355452464033;3420.931581368372;3422.8459719312614;3423.3324501722163;3453.240287136829;6041.848953776158;6058.939410605898;6064.818055458338;6079.4528492435675;6107.05340672196;6011.489023433002;6075.210222671058;6082.391760823915;6085.2393450818645;6092.482139018178;5373.086575282202;5380.7309634518115;5399.244105825188;5407.297051383314;5536.363318218396;5271.367435907678;5299.5760339172775;5344.331958505187;5507.228987941691;5511.944880551183;5398.974194902976;5413.188021796742;5415.187721841716;5421;5422.40353561108;5399.865003374908;5403.135498320269;5406.351237851443;5414.052540805344;5431.266778984866;4465.24090904547;5222.808807361255;5346.893062138774;5355.410904800468;5357.732113394315;6020.127081573148;6032.125341825411;6035.456808887221;6038.758449662008;6042.395760423967;6203.038529028008;6241.375862413768;6242.750289988395;6261.217347831521;6273.090401891745;5516.365617953947;6248.031555108965;6268.404501572389;6273.62358258506;6366.0769188467875;6080.574359794816;6106.114613381079;6157.4458298753325;6160.7535698572;6166.969165154191;6264.906026409608;6589.81048682786;6636.203655561315;6637.336266373421;6648.1025061616065;5305.522502974751;6134.5590473767;6270.184875966095;6276.748930042839;6293.181886354836;4889.681067339206;6222.253329600387;6277.748890044347;6302.401271879165;6377.521685873486;6341.968290158519;6356.300806911236;6374.521910856704;6376.681165941777;6385.36146385367;6368.5860419072615;6377.585456945284;6385;6390.392912673288;6395.872082558416;5015.924761128586;5069.290299358093;5072.619553533426;5132.050570644443;5180.922286165711;3810.714196435223;4537.636989040869;4541.114482675913;4543.432070991126;4565.109803588269;6126.785562505291;6132.264128304543;6136.048912418582;6145.262568491809;6158.922188616974;6216.222972128484;6250.718717657716;6471.126397936312;6495.837604059936;6502.089707440962;5172.663776854492;5945.672987985702;6052.606580572248;6115.149072927005;6122.12371750169;5688.687122208235;5692.288463942007;5699.629524080921;5701.714914254354;5701.973644743903;6689.297623749436;6721.26066132725;6722.294159113419;6723.495737819585;6732.966335168391;6157.815265542041;6242.094896240111;6248.68756562161;6283.59156654816;6286.245650521967;5595.916061259003;5649.322081350264;6472.514561407819;6482.70827812759;6486.870262594816;6358.0780786784735;6358.586691865199;6359.618422894641;6377.521685873486;6379.425851673372;6421.133147025092;6425.357464253537;6438.163038804925;6441.291457939529;6457.128287681103;6127.356627554221;6162.815115546541;6261.96869015652;6263.530235232284;6269.777393408424;6232.096346029891;6237.126802247598;6248;6249.78125765596;6251.156093927261;6122.173506576733;6129.02260737745;6133.2428548619655;6223.0354295084335;6242.687865606792;6276.340984196776;6284.402981716729;6285.308616052322;6293.559450838397;6293.55945083858;6218.067289906369;6239.313675495601;6255.906161407674;6262.15460912771;6263.561550691408 18:25:20 INFO - PROCESS | 1641 | |1;ext.html;748.6631016042783;792.3225642076025;813.8046868751497;875.5534677314567;888.205056474455;27731.029413970515;40817;41547.376789348185;41558.168836623285;41651.37522937158;42344.36483452743;42482.36276455856;42508;42562.148757024916;42602;7326.560406375608;9179.173874351323;9328.440293582373;9337.953310233474;9355.298352623546;7345.926540734605;7426.145993210769;7435.033445652058;7439.7396091136825;11051.447427628627;11218.102551795877;11224.214304998653;11225.214235003554;11240.718982025433;11264.324140551593;11141.610043648474;11156.776864462665;11163.776724465546;11176.21766476347;11185.944070279678;7349.706011759523;7548.698052077911;10925.344479331265;10994.780104397947;11011;39543.8022809887;40000.59999399998;40034.59965400338;40132.79933600313;42134.78932605348;3838.846446142151;3839.2897313997;3891.805409729502;3947.822347994347;4023.517177938637;26965.460690786076;27292.90828366863;28267.57598636022;28516.144515664357;29050.854745726137;3111.144435280316;3182.3317103408313;3184.108449634083;3221.21080335317;3230.337780754963;361244;361452;361621;361884;362315;52429;52446.73776631092;52553.474465255626;52613;52633.73683131636;55552.72223638856;55594.444055558924;55608.44391556113;55643.44356556464;55817.72091139518;14636.487722929647;14723.043002204822;14763.63090922714;14772.26138693061;14782.48261310849;12576.496940122475;12583.433745481476;12589.559365422167;12593.244405335527;12603.18079324841;11017.063549598213;11165.720856978478;11378.715532111597;11902;13046.608601741962;5450.29146210998;5705.600607957404;5751.568632352507;5752.223449834302;5760.3951585083805;944.8724422202914;947.7914858731206;962.8459446488528;970.854371844229;1010.5604062232952;27824.860875695897;29188.05961195254;29367.55948660772;29656.2585935349;29792.553111703342;964.6735738359755;1095.2880627592128;1109.9944500277609;1127.8533790607169;1157.2709193208225;13983.161010339412;14101.153930764187;14359.78460323096;14400.423983040771;14400.639984000483;295345;304033;323127;323658;324557.3772131124;42964.92587685271;51789.7410512945;52192.47807521952;52366.21450678243;52480.950380992166;1395.7138786548733;1437.755581551118;1439.6400899775056;1442.3076923076983;1445.6674964757954;26026.60960085638;26579.33551661224;26965.325866853487;27119.322016949733;27153;39650.20699585934;39808.20383592254;39845;39900.401493977035;39912.80043599763;294633;294799;294813;295012.04987950705;295078;30154.396912062075;30206.244843879078;30222.395552088394;30240.941567045047;30267.545986809775;39625.207495850496;39688.80155599204;40720.38919416152;40741.38887916743;40961.590384095776;32105;32997.34005319928;33347.49978750272;33877.83061084679;34454.31091378108;33871.32257354888;34636.65363346435;34764.47853282152;34949.65050349464;34971.30057398888;33458;33776.83111584427;33813.830930845186;34242.48636270408;34406.65593344034;28728.850845966765;29444.852775735984;29674.851625741732;29779.255518612208;29890.10329690113;21640.350789476342;22955.6556651647;23005.07979680794;23199.420014499774;23241.186558470374 18:25:20 INFO - PROCESS | 1641 | |2;jquery.html;380.1332960849265;403.75572778469024;421.20182254627474;421.74905930971073;442.8516446990258;11672.649820505389;20733.170673173074;21054.684179737316;21240.89379553104;21440.785592144075;21471.785282147175;21772.237971670995;22247.1101155954;22458;22749.4312642184;6581.144451221335;6652.035454859055;6773.7967860964;6831.829204269883;6896;6724.5965242085385;6735.764248251312;6779.288174741655;6781.287964763703;6799.490038247127;6529.902051469208;6698.1292431983775;6753.4259587935085;6771.661416929134;6777.356151165632;6681.832954176136;6748;6815.625140617264;6824.488163387766;6829.078074459959;6568.113304703852;6650.800475985729;6653.334666533308;6659.500537459661;6661.3005634408655;27094.780734866978;27214;27317.45365092687;27574.034908778096;27978.580321295198;2854.4433835402106;2867.225849020753;2873.5976963225166;2880.135959212249;2917.7957542972;15779.211039447982;16385.91807040957;16479.34082636693;16783.748243776354;16928.984260944384;1888.2824526679774;2074.8962551872346;2203.7796220377836;2216.4791274050585;2222.5550396884764;141322;162547;162873;168836;173502.99999999875;14894.957352985404;16283.83716162847;19986.90006549958;19992.10035548404;20408.285710000375;29601.703982959894;30333.544996825065;30998.070057898298;31284.687153128634;31557.84221078926;8382.580870956428;8747;8873.911260887438;9756.46339451337;10090.091891729633;8269.545175015312;8651.6971905983;8660.437071590326;8709.12908709124;8783.516906570072;7889.934858794104;9182.63269469227;9443.622255109856;9520.619175233052;9525.523723813782;3960.2475529649273;4015.9397609035886;4046.251443482965;4053.1558141045753;4061.3298805696727;847.4152834544145;855.2473823035689;863.8963324401112;879.2438502887512;882.9779255518534;19054.523636909187;20480.078396472196;21165.89417052905;21259.68110478344;21368.145274188857;890.9465432073966;904.7376260884402;906.3383729877227;923.4274749655257;930.093159169804;8986;11010.284331518425;11085.83371249432;11283.266587671775;11371;720945;730592;735189;744914;747891;141504;144927;148531;149123.25438372738;152976.2351188237;1170.3855475875234;1177.5466445418576;1192.8270400791837;1203.921745086584;1210.6852218423098;18173;19064.95142767161;19224.519387014876;19243;19307.324243651405;53939.190912137135;54795;64164.679176603815;65022.69954600976;66702.33244168741;64033;75990;91746.08253917376;92621.61067584126;93607.53196233975;12203.572874949334;12879;12913.418896149507;13172.736545269232;13491.718286762742;14063.718725625635;14081.647958800702;14403.063800852911;14503.1298122113;14504.419823207376;11735.589254376056;11817.881821181678;11935.403229838646;12107.394630268274;12175.14773965814;10417.583296668048;11723.941380293043;11825.408729563316;12044.879551204376;12115.333656648969;10484.737881553023;11281.32312061279;11707.648770536898;12085.697857553632;12201.450934707782;12970.740585188432;12975.156614820007;12988.935055324662;13298.734025319633;13325;11832.230904991147;11966.700832479257;12076.879231207575;12121.27272363661;12200.901918827349 18:25:20 INFO - PROCESS | 1641 | |3;mootools.html;1508.132823626415;1530.2501774130678;1554.5258696097692;1563.179330851303;1568.2394038891139;10382.377057376554;10977.670669879917;10982.890171098297;10986.945065274682;11019.393933333664;10775.892241077607;11198.440077996109;11203.887961120387;11207.271527350716;11214.10287177026;2386.6658667786483;2425.6967879015124;2427.5509030829353;2427.6844010278637;2435.9391015224674;2361.161787565407;2383.594788885885;2395.6406539019113;2397.3407312988893;2401.86789726565;2378.4410663494064;2400.855948643078;2410.529946660402;2411.023535468138;2412.9396765080837;2350.4358953851124;2366.4912043910554;2376.1802178248477;2376.3346263046374;2377.940551486209;2327.9650805237934;2332.8483648562787;2405.290439320405;2408.963865542018;2410.3251089694913;10927.672169834917;11056.944715276453;11064.225504214708;11080.501377438035;11092.445377731081;5742.7415766290615;5920.437558431942;5923.289205295349;5930.940690593082;5947.316058653267;3378.983105084483;3470.444728843398;3479.9478007829903;3506.68439840413;3553.129483276588;971.3006635222629;1134.8098170530873;1146.7018575170432;1166.8133098704166;1189.9583514576948;13559.322033898365;13964.371603277878;14197.503087391891;15248.542543723706;15276.312565934559;4216.7259128156575;4340.414044104069;4341.544137865541;4344.782760861945;4510.751908645056;11173.553057877756;11179.329240245612;11188.888111118948;11231.887681123084;11267.985881270724;5582.302212223472;5587.497125258747;5589.636673616202;5596.104623260258;5603.8599035023635;4874.780634871439;5139.511746384088;5196.220566914993;5205.531502164823;5242.1088414969545;3695.427208782643;3791.1280405506886;3795.6773674237998;3845.576986531479;3862;907.587047893207;1030.283952652914;1035.8808736995343;1037.6938803053117;1046.1578429364283;894.5124906925741;916.6984964755752;925.0795458518817;935.3218916285639;971.0532231074787;6419.74321027163;6469.061986012002;6469.094326794253;6472;6511.251206111263;906.6554709210458;908.9227415669742;927.9768005799774;933.3466573398648;937.9953100234592;3056.587360706321;3065.8773649054233;3077.261457250244;3120.0795765249304;3207.4066297734994;13463.461461541625;13967.720645587031;13968.301584920713;13978.370973306226;14026.368813403216;12567.120301578985;13165.60503184906;13717.794233086512;13875.653108672162;13894;9896.406215627085;10167.186625069977;10171.593136274614;10177.389356638625;10184.185265178769;5810.331811841692;6470.288268290578;6481.643509606828;6613.14029176204;6689.69896354675;11608.593699220486;11738.354390508604;11862.406879656155;11978.520859165536;12008.699782505508;10234.02776736209;10531.104856087011;10683.94658026705;12350.258984460961;12386.876131238572;7816.84366312682;7901.051873775184;9989.400635961574;10093.848592271268;10170.542325595217;8775.42959707617;10390.22073344487;10510.421926794099;10522.210834187314;10525;8777.253933415732;8993.010768815295;9126.224270936778;9193.586288616894;9322.720318390458;8407.2433480987;8506.829863402822;8896.332775041768;8906;8939.910600893907;7643.197464266172;7799.454038217497;7815.062192536705;7822.413319000984;7823.413244006609;5704.486596206361;5733.8566535836935;5777.2200752897825;5911.261092363455;5934.881302373842;5817.0983497557945;5855.443732845373;5967.6121052131475;6066.817995460143;6171.1668924694595 18:25:20 INFO - PROCESS | 1641 | |4;prototype.html;310.2878892940701;365.80063865193466;381.84916957801687;383.3406540749911;385.91509867829075;4995.325631039808;5082.923756143656;5099.821506247277;5112.590992720583;5173.6119791015635;4585.816567337302;4597.218472859614;4891.070696567658;4941.171766446411;4999.925001124986;4008.3185858403995;4113.526944401402;4180.937285940713;4183.6653067754505;4196.370544418339;3925.9607403926025;3998.3202855514487;4101.8974525636795;4183.602557757008;4257.063446041866;3993.760374377532;4085.9387109193385;4101;4186.49762028557;4197.244495990721;4078.877633670995;4103.9179216415505;4120.320147175742;4122.876313710593;4174.227767862955;3506.7194624429985;3547.9822600887087;3687.4284485904727;3842.346801043829;3857.9421308680394;4095.610916962884;4866.902661946777;4973.651844370896;5026.472220416877;5059.013492368989;4639.909621239006;4699.906001879944;4746.644001699887;4795.472498025214;4819.481863213076;4663.251280769711;5152.896942061137;5161.561267292284;5482.862928426782;5511;3803.861814003458;3891.6886649068006;4152.211079894811;4290.018437186157;4358.474533913105;4796.592289655348;5242.8689282767855;5369.543588794918;5486.945130548724;5511.53151982082;4219.3333633215325;4228.682848786353;4230.5769423057445;4230.703850730481;4245.97877010613;4162.729422587522;4289.82840686369;4307.289297265916;4473.731576105444;4480.215962206666;4100.712950093524;4655.441347038377;4756.262779269219;4768.9031522749965;4851.320815085891;4753.976230118897;5009.970961760493;5327.016870552503;5369.40936496985;5378.354597448331;1504.7065822164573;1507.7485686879863;1521.73369660309;1539.2919257141616;1545.2814441284856;1039.968800935958;1046.6336782126348;1109.5561775289948;1116.9106471482262;1162.7383838636408;4507.304010711186;4605.111904119295;4959.702417854868;5025.572826309732;5070.416902056237;5086.64393492452;5338.145896656516;5523.636327301468;5573.777048918079;5619;4573.977130114395;4654.394928659252;4837.879053023632;4917.262410638361;5084.3644544431945;2457.6436416719835;2604.5442047641586;2670.5994100885023;2855.214815925637;2887.588863299127;5233.528982391603;5513.884655169896;5591.07747221712;5731.77072917087;5786.971065144731;4966.726830024311;5008.398992120969;5212.4266330703595;5570.6657600544095;5725.971370143206;5274.4198138204765;5373.942673281726;5789.94210057894;5794.956907756644;5796.420357964254;4810;5626.887462250814;5632.662040277596;5642.633228840113;5660.726336574237;4752.144613969519;4922.926156107733;5112.156494178419;5393.191021346829;5397.865053373697;5047.545720884991;5069.946671598009;5414.810481633282;5488.929658716512;5624.256480491061;3780.4518344839294;3905.902352441212;3980.980095099506;4339.609435150852;4388.407564978687;4846.684965477232;5259.2141151551905;5279.762410691451;5369.140937449989;5644.491995720405;5391.57379179363;5427.945720542744;5428.67427954324;5437.510624043855;5458.399576046552;5022.196448568212;5100.69395836251;5375.569954403716;5414.888193604576;5417.620766546304;4554;4664.533546645308;4902.240152776183;5249.370075590801;5293.444188360167;4519.593236608721;4721.527847215251;5287.3919499256535;5333.306670132858;5336.519713225828;4948.703077815343;5032.112957640885;5191.662541934762;5250.343707036621;5327.121025030827 18:25:20 INFO - PROCESS | 1641 | |5;yui.html;710.4032612605417;751.7707099334705;761.6191904047976;838.4172999765163;843.0690095180403;14753.336099875505;15451.613709657264;15534.378624854991;15557.455489057871;15594.688106237869;13671.453141874337;14086.436542538286;14130.92934535326;14313.78529322061;14511.854881451181;813.6582635293162;904.1997831918734;940.539135823445;944.4380593546829;944.8110377924443;916.8533034714447;934.397313732642;939.8684184214216;939.9389039712397;940.1209868772719;944.1927152284801;1003.0120331473493;1004.3471743366797;1006.4816619440957;1007.9798404031915;996.5067291690588;1003.6788227767117;1004.4324956399684;1005.9497025148713;1006.3006210683578;742.8699977503916;743.680217506472;743.7173873927927;743.7619961612286;743.9256074392572;15679.764803527956;15751.661108805765;15837.920810395874;15860.52418427449;15931.681366372724;13867.23730194839;13984.230867302294;14028.929855350658;14216.075955062888;14394.064385814992;11174.44127793607;11604.535818567247;11612.645856188325;11732.824007639892;11901.345426001566;3374.460086386191;3526.6297038811067;3625.7643253188457;3702.740808143431;3817.8472861085525;102495.8502489853;109276;109746;110608;111150.44424777824;10202.387856728472;10383.84424233637;10673.573057077785;10791.83812242817;10802;22352.888235559043;22591.887040564692;22596.774032259797;22639.320820375415;22661.43346416353;7687.077550693841;7701.075870895528;7776.144624091346;7828.334591559781;7967.521948683097;4267.722598031118;4278.251306021434;4370.519242883281;4380.802863871133;4556.974680696817;3462.6018007928906;3564.7682900611376;3565.5899571549085;3565.6969157621375;3568.5896121946307;514.747339109177;616.3035769580346;619.7520991603395;622.2595111816923;623.1525125828869;623.5215020294294;667.442685357722;671.4628297362091;711.3064761857252;750.2572453271222;8387.287080598095;9635;9864.605415783288;10134.037266459674;10362.083014642316;639.8464368551607;643.4948565376158;661.3915198017835;667.1678723817427;695.544418405945;4791.928121078186;4858.368412106403;4892.19278818998;4898.167311556973;4908.730019848943;91156;94074;94195.9353048524;95834.64578739711;97280.51359743156;78468;79960;82565.58717206375;86907;90571.54714226519;64554.70890582162;67412.662936685;73497.2650273501;79427.60286198532;83661;11023.489015766727;11023.503942322775;11180.776384472427;11324.37715925632;11387;39994.40008399818;49188.50811491839;49726.751366242934;50536;50607.49392506028;54347;54711.45288547223;64011;64913.026304606414;65761.67119164566;12979.351032448529;13818.861811381757;13837.10058846212;14248.715025699636;14368.712625747217;14269.500567480089;14304.713905722036;14582.270886455422;15029.473968411054;15150.3182356792;12294.016478681506;12933.595176300085;13823.377947992565;14240.643983900485;14313;12849.293288869201;12957.676058098623;13194.406251718503;13419.329033548478;13459.46162153542;13358.666033348854;13642.386092625657;13644.727105458034;13711.108777929403;13858.86141138616;10078.344907580984;11110;11133.165012623924;11899.226550274205;12002.459889305177;11443.097924502046;11513.769724605294;11635.47640356169;11647.534098636299;11698.239614425034 18:25:20 INFO - PROCESS | 1641 | __end_tp_report 18:25:20 INFO - PROCESS | 1641 | __start_cc_report 18:25:20 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,1849 18:25:20 INFO - PROCESS | 1641 | __end_cc_report 18:25:20 INFO - PROCESS | 1641 | __startTimestamp1489541120477__endTimestamp 18:25:20 INFO - PROCESS | 1641 | 18:25:20 INFO - PROCESS | 1641 | ------- Summary: start ------- 18:25:20 INFO - PROCESS | 1641 | Number of tests: 6 18:25:20 INFO - PROCESS | 1641 | 18:25:20 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5907.05 Median:6157.82 stddev:680.32 (11.0%) stddev-sans-first:653.17 18:25:20 INFO - PROCESS | 1641 | Values: 3392.4 3420.9 3422.8 3423.3 3453.2 6041.8 6058.9 6064.8 6079.5 6107.1 6011.5 6075.2 6082.4 6085.2 6092.5 5373.1 5380.7 5399.2 5407.3 5536.4 5271.4 5299.6 5344.3 5507.2 5511.9 5399.0 5413.2 5415.2 5421.0 5422.4 5399.9 5403.1 5406.4 5414.1 5431.3 4465.2 5222.8 5346.9 5355.4 5357.7 6020.1 6032.1 6035.5 6038.8 6042.4 6203.0 6241.4 6242.8 6261.2 6273.1 5516.4 6248.0 6268.4 6273.6 6366.1 6080.6 6106.1 6157.4 6160.8 6167.0 6264.9 6589.8 6636.2 6637.3 6648.1 5305.5 6134.6 6270.2 6276.7 6293.2 4889.7 6222.3 6277.7 6302.4 6377.5 6342.0 6356.3 6374.5 6376.7 6385.4 6368.6 6377.6 6385.0 6390.4 6395.9 5015.9 5069.3 5072.6 5132.1 5180.9 3810.7 4537.6 4541.1 4543.4 4565.1 6126.8 6132.3 6136.0 6145.3 6158.9 6216.2 6250.7 6471.1 6495.8 6502.1 5172.7 5945.7 6052.6 6115.1 6122.1 5688.7 5692.3 5699.6 5701.7 5702.0 6689.3 6721.3 6722.3 6723.5 6733.0 6157.8 6242.1 6248.7 6283.6 6286.2 5595.9 5649.3 6472.5 6482.7 6486.9 6358.1 6358.6 6359.6 6377.5 6379.4 6421.1 6425.4 6438.2 6441.3 6457.1 6127.4 6162.8 6262.0 6263.5 6269.8 6232.1 6237.1 6248.0 6249.8 6251.2 6122.2 6129.0 6133.2 6223.0 6242.7 6276.3 6284.4 6285.3 6293.6 6293.6 6218.1 6239.3 6255.9 6262.2 6263.6 18:25:20 INFO - PROCESS | 1641 | 18:25:20 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:47743.04 Median:178777.36 stddev:85307.24 (47.7%) stddev-sans-first:85473.57 18:25:20 INFO - PROCESS | 1641 | Values: 748.7 792.3 813.8 875.6 888.2 27731.0 40817.0 41547.4 41558.2 41651.4 42344.4 42482.4 42508.0 42562.1 42602.0 7326.6 9179.2 9328.4 9338.0 9355.3 7345.9 7426.1 7435.0 7439.7 11051.4 11218.1 11224.2 11225.2 11240.7 11264.3 11141.6 11156.8 11163.8 11176.2 11185.9 7349.7 7548.7 10925.3 10994.8 11011.0 39543.8 40000.6 40034.6 40132.8 42134.8 3838.8 3839.3 3891.8 3947.8 4023.5 26965.5 27292.9 28267.6 28516.1 29050.9 3111.1 3182.3 3184.1 3221.2 3230.3 361244.0 361452.0 361621.0 361884.0 362315.0 52429.0 52446.7 52553.5 52613.0 52633.7 55552.7 55594.4 55608.4 55643.4 55817.7 14636.5 14723.0 14763.6 14772.3 14782.5 12576.5 12583.4 12589.6 12593.2 12603.2 11017.1 11165.7 11378.7 11902.0 13046.6 5450.3 5705.6 5751.6 5752.2 5760.4 944.9 947.8 962.8 970.9 1010.6 27824.9 29188.1 29367.6 29656.3 29792.6 964.7 1095.3 1110.0 1127.9 1157.3 13983.2 14101.2 14359.8 14400.4 14400.6 295345.0 304033.0 323127.0 323658.0 324557.4 42964.9 51789.7 52192.5 52366.2 52481.0 1395.7 1437.8 1439.6 1442.3 1445.7 26026.6 26579.3 26965.3 27119.3 27153.0 39650.2 39808.2 39845.0 39900.4 39912.8 294633.0 294799.0 294813.0 295012.0 295078.0 30154.4 30206.2 30222.4 30240.9 30267.5 39625.2 39688.8 40720.4 40741.4 40961.6 32105.0 32997.3 33347.5 33877.8 34454.3 33871.3 34636.7 34764.5 34949.7 34971.3 33458.0 33776.8 33813.8 34242.5 34406.7 28728.9 29444.9 29674.9 29779.3 29890.1 21640.4 22955.7 23005.1 23199.4 23241.2 18:25:20 INFO - PROCESS | 1641 | 18:25:20 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:42635.75 Median:27154.39 stddev:122909.91 (452.6%) stddev-sans-first:123213.75 18:25:20 INFO - PROCESS | 1641 | Values: 380.1 403.8 421.2 421.7 442.9 11672.6 20733.2 21054.7 21240.9 21440.8 21471.8 21772.2 22247.1 22458.0 22749.4 6581.1 6652.0 6773.8 6831.8 6896.0 6724.6 6735.8 6779.3 6781.3 6799.5 6529.9 6698.1 6753.4 6771.7 6777.4 6681.8 6748.0 6815.6 6824.5 6829.1 6568.1 6650.8 6653.3 6659.5 6661.3 27094.8 27214.0 27317.5 27574.0 27978.6 2854.4 2867.2 2873.6 2880.1 2917.8 15779.2 16385.9 16479.3 16783.7 16929.0 1888.3 2074.9 2203.8 2216.5 2222.6 141322.0 162547.0 162873.0 168836.0 173503.0 14895.0 16283.8 19986.9 19992.1 20408.3 29601.7 30333.5 30998.1 31284.7 31557.8 8382.6 8747.0 8873.9 9756.5 10090.1 8269.5 8651.7 8660.4 8709.1 8783.5 7889.9 9182.6 9443.6 9520.6 9525.5 3960.2 4015.9 4046.3 4053.2 4061.3 847.4 855.2 863.9 879.2 883.0 19054.5 20480.1 21165.9 21259.7 21368.1 890.9 904.7 906.3 923.4 930.1 8986.0 11010.3 11085.8 11283.3 11371.0 720945.0 730592.0 735189.0 744914.0 747891.0 141504.0 144927.0 148531.0 149123.3 152976.2 1170.4 1177.5 1192.8 1203.9 1210.7 18173.0 19065.0 19224.5 19243.0 19307.3 53939.2 54795.0 64164.7 65022.7 66702.3 64033.0 75990.0 91746.1 92621.6 93607.5 12203.6 12879.0 12913.4 13172.7 13491.7 14063.7 14081.6 14403.1 14503.1 14504.4 11735.6 11817.9 11935.4 12107.4 12175.1 10417.6 11723.9 11825.4 12044.9 12115.3 10484.7 11281.3 11707.6 12085.7 12201.5 12970.7 12975.2 12988.9 13298.7 13325.0 11832.2 11966.7 12076.9 12121.3 12200.9 18:25:20 INFO - PROCESS | 1641 | 18:25:20 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:6601.43 Median:2427.62 stddev:4151.20 (171.0%) stddev-sans-first:4145.20 18:25:20 INFO - PROCESS | 1641 | Values: 1508.1 1530.3 1554.5 1563.2 1568.2 10382.4 10977.7 10982.9 10986.9 11019.4 10775.9 11198.4 11203.9 11207.3 11214.1 2386.7 2425.7 2427.6 2427.7 2435.9 2361.2 2383.6 2395.6 2397.3 2401.9 2378.4 2400.9 2410.5 2411.0 2412.9 2350.4 2366.5 2376.2 2376.3 2377.9 2328.0 2332.8 2405.3 2409.0 2410.3 10927.7 11056.9 11064.2 11080.5 11092.4 5742.7 5920.4 5923.3 5930.9 5947.3 3379.0 3470.4 3479.9 3506.7 3553.1 971.3 1134.8 1146.7 1166.8 1190.0 13559.3 13964.4 14197.5 15248.5 15276.3 4216.7 4340.4 4341.5 4344.8 4510.8 11173.6 11179.3 11188.9 11231.9 11268.0 5582.3 5587.5 5589.6 5596.1 5603.9 4874.8 5139.5 5196.2 5205.5 5242.1 3695.4 3791.1 3795.7 3845.6 3862.0 907.6 1030.3 1035.9 1037.7 1046.2 894.5 916.7 925.1 935.3 971.1 6419.7 6469.1 6469.1 6472.0 6511.3 906.7 908.9 928.0 933.3 938.0 3056.6 3065.9 3077.3 3120.1 3207.4 13463.5 13967.7 13968.3 13978.4 14026.4 12567.1 13165.6 13717.8 13875.7 13894.0 9896.4 10167.2 10171.6 10177.4 10184.2 5810.3 6470.3 6481.6 6613.1 6689.7 11608.6 11738.4 11862.4 11978.5 12008.7 10234.0 10531.1 10683.9 12350.3 12386.9 7816.8 7901.1 9989.4 10093.8 10170.5 8775.4 10390.2 10510.4 10522.2 10525.0 8777.3 8993.0 9126.2 9193.6 9322.7 8407.2 8506.8 8896.3 8906.0 8939.9 7643.2 7799.5 7815.1 7822.4 7823.4 5704.5 5733.9 5777.2 5911.3 5934.9 5817.1 5855.4 5967.6 6066.8 6171.2 18:25:20 INFO - PROCESS | 1641 | 18:25:20 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4468.33 Median:4842.28 stddev:1241.47 (25.6%) stddev-sans-first:1205.08 18:25:20 INFO - PROCESS | 1641 | Values: 310.3 365.8 381.8 383.3 385.9 4995.3 5082.9 5099.8 5112.6 5173.6 4585.8 4597.2 4891.1 4941.2 4999.9 4008.3 4113.5 4180.9 4183.7 4196.4 3926.0 3998.3 4101.9 4183.6 4257.1 3993.8 4085.9 4101.0 4186.5 4197.2 4078.9 4103.9 4120.3 4122.9 4174.2 3506.7 3548.0 3687.4 3842.3 3857.9 4095.6 4866.9 4973.7 5026.5 5059.0 4639.9 4699.9 4746.6 4795.5 4819.5 4663.3 5152.9 5161.6 5482.9 5511.0 3803.9 3891.7 4152.2 4290.0 4358.5 4796.6 5242.9 5369.5 5486.9 5511.5 4219.3 4228.7 4230.6 4230.7 4246.0 4162.7 4289.8 4307.3 4473.7 4480.2 4100.7 4655.4 4756.3 4768.9 4851.3 4754.0 5010.0 5327.0 5369.4 5378.4 1504.7 1507.7 1521.7 1539.3 1545.3 1040.0 1046.6 1109.6 1116.9 1162.7 4507.3 4605.1 4959.7 5025.6 5070.4 5086.6 5338.1 5523.6 5573.8 5619.0 4574.0 4654.4 4837.9 4917.3 5084.4 2457.6 2604.5 2670.6 2855.2 2887.6 5233.5 5513.9 5591.1 5731.8 5787.0 4966.7 5008.4 5212.4 5570.7 5726.0 5274.4 5373.9 5789.9 5795.0 5796.4 4810.0 5626.9 5632.7 5642.6 5660.7 4752.1 4922.9 5112.2 5393.2 5397.9 5047.5 5069.9 5414.8 5488.9 5624.3 3780.5 3905.9 3981.0 4339.6 4388.4 4846.7 5259.2 5279.8 5369.1 5644.5 5391.6 5427.9 5428.7 5437.5 5458.4 5022.2 5100.7 5375.6 5414.9 5417.6 4554.0 4664.5 4902.2 5249.4 5293.4 4519.6 4721.5 5287.4 5333.3 5336.5 4948.7 5032.1 5191.7 5250.3 5327.1 18:25:20 INFO - PROCESS | 1641 | 18:25:20 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:19932.13 Median:3418.53 stddev:28068.27 (821.1%) stddev-sans-first:28109.90 18:25:20 INFO - PROCESS | 1641 | Values: 710.4 751.8 761.6 838.4 843.1 14753.3 15451.6 15534.4 15557.5 15594.7 13671.5 14086.4 14130.9 14313.8 14511.9 813.7 904.2 940.5 944.4 944.8 916.9 934.4 939.9 939.9 940.1 944.2 1003.0 1004.3 1006.5 1008.0 996.5 1003.7 1004.4 1005.9 1006.3 742.9 743.7 743.7 743.8 743.9 15679.8 15751.7 15837.9 15860.5 15931.7 13867.2 13984.2 14028.9 14216.1 14394.1 11174.4 11604.5 11612.6 11732.8 11901.3 3374.5 3526.6 3625.8 3702.7 3817.8 102495.9 109276.0 109746.0 110608.0 111150.4 10202.4 10383.8 10673.6 10791.8 10802.0 22352.9 22591.9 22596.8 22639.3 22661.4 7687.1 7701.1 7776.1 7828.3 7967.5 4267.7 4278.3 4370.5 4380.8 4557.0 3462.6 3564.8 3565.6 3565.7 3568.6 514.7 616.3 619.8 622.3 623.2 623.5 667.4 671.5 711.3 750.3 8387.3 9635.0 9864.6 10134.0 10362.1 639.8 643.5 661.4 667.2 695.5 4791.9 4858.4 4892.2 4898.2 4908.7 91156.0 94074.0 94195.9 95834.6 97280.5 78468.0 79960.0 82565.6 86907.0 90571.5 64554.7 67412.7 73497.3 79427.6 83661.0 11023.5 11023.5 11180.8 11324.4 11387.0 39994.4 49188.5 49726.8 50536.0 50607.5 54347.0 54711.5 64011.0 64913.0 65761.7 12979.4 13818.9 13837.1 14248.7 14368.7 14269.5 14304.7 14582.3 15029.5 15150.3 12294.0 12933.6 13823.4 14240.6 14313.0 12849.3 12957.7 13194.4 13419.3 13459.5 13358.7 13642.4 13644.7 13711.1 13858.9 10078.3 11110.0 11133.2 11899.2 12002.5 11443.1 11513.8 11635.5 11647.5 11698.2 18:25:20 INFO - PROCESS | 1641 | -------- Summary: end -------- 18:25:20 INFO - PROCESS | 1641 | 18:25:21 INFO - TEST-INFO | 1641: exit 0 18:25:21 INFO - TEST-OK | dromaeo_css | took 1120608ms 18:25:21 INFO - TEST-START | kraken 18:25:21 INFO - operating with platform_type : mac_ 18:25:21 INFO - Initialising browser for kraken test... 18:25:21 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpTsA1uE/profile http://localhost:49240/getInfo.html) 18:25:23 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 18:25:23 INFO - PROCESS | 1649 | colorDepth:24 18:25:23 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 18:25:23 INFO - PROCESS | 1649 | __metrics 18:25:23 INFO - PROCESS | 1649 | 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" 18:25:23 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:25:24 INFO - TEST-INFO | 1649: exit 0 18:25:24 INFO - Browser initialized. 18:25:24 INFO - Running cycle 1/1 for kraken test... 18:25:24 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/T/tmpTsA1uE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:25:36 INFO - PROCESS | 1650 | RSS: Main: 214319104 18:25:36 INFO - PROCESS | 1650 | 18:25:39 INFO - PROCESS | 1650 | 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) 18:25:40 INFO - PROCESS | 1650 | RSS: Main: 267378688 18:25:40 INFO - PROCESS | 1650 | 18:25:42 INFO - PROCESS | 1650 | 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) 18:25:43 INFO - PROCESS | 1650 | RSS: Main: 278032384 18:25:43 INFO - PROCESS | 1650 | 18:25:46 INFO - PROCESS | 1650 | 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) 18:25:47 INFO - PROCESS | 1650 | RSS: Main: 375316480 18:25:47 INFO - PROCESS | 1650 | 18:25:49 INFO - PROCESS | 1650 | 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) 18:25:49 INFO - PROCESS | 1650 | RSS: Main: 278876160 18:25:49 INFO - PROCESS | 1650 | 18:25:51 INFO - PROCESS | 1650 | 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) 18:25:52 INFO - PROCESS | 1650 | RSS: Main: 280326144 18:25:52 INFO - PROCESS | 1650 | 18:25:55 INFO - PROCESS | 1650 | 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) 18:25:56 INFO - PROCESS | 1650 | RSS: Main: 321712128 18:25:56 INFO - PROCESS | 1650 | 18:25:59 INFO - PROCESS | 1650 | 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) 18:25:59 INFO - PROCESS | 1650 | RSS: Main: 322457600 18:25:59 INFO - PROCESS | 1650 | 18:26:02 INFO - PROCESS | 1650 | 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) 18:26:03 INFO - PROCESS | 1650 | RSS: Main: 318193664 18:26:03 INFO - PROCESS | 1650 | 18:26:05 INFO - PROCESS | 1650 | 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) 18:26:05 INFO - PROCESS | 1650 | RSS: Main: 295845888 18:26:05 INFO - PROCESS | 1650 | 18:26:07 INFO - PROCESS | 1650 | 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) 18:26:08 INFO - PROCESS | 1650 | RSS: Main: 297517056 18:26:08 INFO - PROCESS | 1650 | 18:26:10 INFO - PROCESS | 1650 | 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) 18:26:11 INFO - PROCESS | 1650 | RSS: Main: 301694976 18:26:11 INFO - PROCESS | 1650 | 18:26:13 INFO - PROCESS | 1650 | 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) 18:26:14 INFO - PROCESS | 1650 | RSS: Main: 320450560 18:26:14 INFO - PROCESS | 1650 | 18:26:17 INFO - PROCESS | 1650 | 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) 18:26:18 INFO - PROCESS | 1650 | RSS: Main: 313978880 18:26:18 INFO - PROCESS | 1650 | 18:26:19 INFO - PROCESS | 1650 | 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) 18:26:20 INFO - PROCESS | 1650 | RSS: Main: 358318080 18:26:20 INFO - PROCESS | 1650 | 18:26:20 INFO - PROCESS | 1650 | __start_tp_report 18:26:20 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 18:26:20 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 18:26:20 INFO - PROCESS | 1650 | |i|pagename|runs| 18:26:20 INFO - PROCESS | 1650 | |0;ai-astar;118;116;116;115;116;116;115;114;115;117 18:26:20 INFO - PROCESS | 1650 | |1;audio-beat-detection;138;107;109;140;110;110;112;109;128;110 18:26:20 INFO - PROCESS | 1650 | |2;audio-dft;189;187;185;186;187;186;184;185;187;185 18:26:20 INFO - PROCESS | 1650 | |3;audio-fft;83;60;61;88;52;77;80;69;75;59 18:26:20 INFO - PROCESS | 1650 | |4;audio-oscillator;88;68;112;97;96;75;83;68;68;84 18:26:20 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;91;90;101;131;89;90;90;89;91;91 18:26:20 INFO - PROCESS | 1650 | |6;imaging-darkroom;96;97;96;99;96;95;96;99;97;96 18:26:20 INFO - PROCESS | 1650 | |7;imaging-desaturate;132;133;89;91;84;83;86;84;84;87 18:26:20 INFO - PROCESS | 1650 | |8;json-parse-financial;63;61;60;62;66;64;63;64;64;65 18:26:20 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;55;55;68;66;84;78;79;57;58;57 18:26:20 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;84;83;84;84;86;82;81;85;84;99 18:26:20 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;139;137;137;137;135;145;134;114;192;147 18:26:20 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;172;163;165;167;165;165;163;163;164;163 18:26:20 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;65;63;64;64;65;64;65;64;61;64 18:26:20 INFO - PROCESS | 1650 | __end_tp_report 18:26:20 INFO - PROCESS | 1650 | __start_cc_report 18:26:20 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,2131 18:26:20 INFO - PROCESS | 1650 | __end_cc_report 18:26:20 INFO - PROCESS | 1650 | __startTimestamp1489541180056__endTimestamp 18:26:20 INFO - PROCESS | 1650 | 18:26:20 INFO - PROCESS | 1650 | ------- Summary: start ------- 18:26:20 INFO - PROCESS | 1650 | Number of tests: 14 18:26:20 INFO - PROCESS | 1650 | 18:26:20 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:115.80 Median:116.00 stddev:1.14 (1.0%) stddev-sans-first:0.88 18:26:20 INFO - PROCESS | 1650 | Values: 118.0 116.0 116.0 115.0 116.0 116.0 115.0 114.0 115.0 117.0 18:26:20 INFO - PROCESS | 1650 | 18:26:20 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:117.30 Median:111.00 stddev:12.87 (11.6%) stddev-sans-first:11.26 18:26:20 INFO - PROCESS | 1650 | Values: 138.0 107.0 109.0 140.0 110.0 110.0 112.0 109.0 128.0 110.0 18:26:20 INFO - PROCESS | 1650 | 18:26:20 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:186.10 Median:186.50 stddev:1.45 (0.8%) stddev-sans-first:1.09 18:26:20 INFO - PROCESS | 1650 | Values: 189.0 187.0 185.0 186.0 187.0 186.0 184.0 185.0 187.0 185.0 18:26:20 INFO - PROCESS | 1650 | 18:26:20 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:70.40 Median:76.00 stddev:11.98 (15.8%) stddev-sans-first:11.81 18:26:20 INFO - PROCESS | 1650 | Values: 83.0 60.0 61.0 88.0 52.0 77.0 80.0 69.0 75.0 59.0 18:26:20 INFO - PROCESS | 1650 | 18:26:20 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:83.90 Median:83.50 stddev:14.77 (17.7%) stddev-sans-first:15.59 18:26:20 INFO - PROCESS | 1650 | Values: 88.0 68.0 112.0 97.0 96.0 75.0 83.0 68.0 68.0 84.0 18:26:20 INFO - PROCESS | 1650 | 18:26:20 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:95.30 Median:90.00 stddev:13.02 (14.5%) stddev-sans-first:13.72 18:26:20 INFO - PROCESS | 1650 | Values: 91.0 90.0 101.0 131.0 89.0 90.0 90.0 89.0 91.0 91.0 18:26:20 INFO - PROCESS | 1650 | 18:26:20 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:96.70 Median:96.50 stddev:1.34 (1.4%) stddev-sans-first:1.39 18:26:20 INFO - PROCESS | 1650 | Values: 96.0 97.0 96.0 99.0 96.0 95.0 96.0 99.0 97.0 96.0 18:26:20 INFO - PROCESS | 1650 | 18:26:20 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:85.00 stddev:19.77 (23.3%) stddev-sans-first:15.89 18:26:20 INFO - PROCESS | 1650 | Values: 132.0 133.0 89.0 91.0 84.0 83.0 86.0 84.0 84.0 87.0 18:26:20 INFO - PROCESS | 1650 | 18:26:20 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:63.20 Median:64.00 stddev:1.81 (2.8%) stddev-sans-first:1.92 18:26:20 INFO - PROCESS | 1650 | Values: 63.0 61.0 60.0 62.0 66.0 64.0 63.0 64.0 64.0 65.0 18:26:20 INFO - PROCESS | 1650 | 18:26:20 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:67.00 stddev:11.10 (16.6%) stddev-sans-first:11.07 18:26:20 INFO - PROCESS | 1650 | Values: 55.0 55.0 68.0 66.0 84.0 78.0 79.0 57.0 58.0 57.0 18:26:20 INFO - PROCESS | 1650 | 18:26:20 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:84.00 stddev:5.05 (6.0%) stddev-sans-first:5.34 18:26:20 INFO - PROCESS | 1650 | Values: 84.0 83.0 84.0 84.0 86.0 82.0 81.0 85.0 84.0 99.0 18:26:20 INFO - PROCESS | 1650 | 18:26:20 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:141.70 Median:138.00 stddev:19.76 (14.3%) stddev-sans-first:20.93 18:26:20 INFO - PROCESS | 1650 | Values: 139.0 137.0 137.0 137.0 135.0 145.0 134.0 114.0 192.0 147.0 18:26:20 INFO - PROCESS | 1650 | 18:26:20 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:165.00 stddev:2.79 (1.7%) stddev-sans-first:1.39 18:26:20 INFO - PROCESS | 1650 | Values: 172.0 163.0 165.0 167.0 165.0 165.0 163.0 163.0 164.0 163.0 18:26:20 INFO - PROCESS | 1650 | 18:26:20 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.90 Median:64.00 stddev:1.20 (1.9%) stddev-sans-first:1.20 18:26:20 INFO - PROCESS | 1650 | Values: 65.0 63.0 64.0 64.0 65.0 64.0 65.0 64.0 61.0 64.0 18:26:20 INFO - PROCESS | 1650 | -------- Summary: end -------- 18:26:20 INFO - PROCESS | 1650 | 18:26:20 INFO - TEST-INFO | 1650: exit 0 18:26:20 INFO - TEST-OK | kraken | took 59803ms 18:26:20 INFO - SUITE-END | took 1180s 18:26:21 INFO - Completed test suite (00:19:40) 18:26:21 INFO - javascript benchmark 18:26:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7597.62802475572, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3392.355452464033, 3420.931581368372, 3422.8459719312614, 3423.3324501722163, 3453.240287136829, 6041.848953776158, 6058.939410605898, 6064.818055458338, 6079.4528492435675, 6107.05340672196, 6011.489023433002, 6075.210222671058, 6082.391760823915, 6085.2393450818645, 6092.482139018178, 5373.086575282202, 5380.7309634518115, 5399.244105825188, 5407.297051383314, 5536.363318218396, 5271.367435907678, 5299.5760339172775, 5344.331958505187, 5507.228987941691, 5511.944880551183, 5398.974194902976, 5413.188021796742, 5415.187721841716, 5421.0, 5422.40353561108, 5399.865003374908, 5403.135498320269, 5406.351237851443, 5414.052540805344, 5431.266778984866, 4465.24090904547, 5222.808807361255, 5346.893062138774, 5355.410904800468, 5357.732113394315, 6020.127081573148, 6032.125341825411, 6035.456808887221, 6038.758449662008, 6042.395760423967, 6203.038529028008, 6241.375862413768, 6242.750289988395, 6261.217347831521, 6273.090401891745, 5516.365617953947, 6248.031555108965, 6268.404501572389, 6273.62358258506, 6366.0769188467875, 6080.574359794816, 6106.114613381079, 6157.4458298753325, 6160.7535698572, 6166.969165154191, 6264.906026409608, 6589.81048682786, 6636.203655561315, 6637.336266373421, 6648.1025061616065, 5305.522502974751, 6134.5590473767, 6270.184875966095, 6276.748930042839, 6293.181886354836, 4889.681067339206, 6222.253329600387, 6277.748890044347, 6302.401271879165, 6377.521685873486, 6341.968290158519, 6356.300806911236, 6374.521910856704, 6376.681165941777, 6385.36146385367, 6368.5860419072615, 6377.585456945284, 6385.0, 6390.392912673288, 6395.872082558416, 5015.924761128586, 5069.290299358093, 5072.619553533426, 5132.050570644443, 5180.922286165711, 3810.714196435223, 4537.636989040869, 4541.114482675913, 4543.432070991126, 4565.109803588269, 6126.785562505291, 6132.264128304543, 6136.048912418582, 6145.262568491809, 6158.922188616974, 6216.222972128484, 6250.718717657716, 6471.126397936312, 6495.837604059936, 6502.089707440962, 5172.663776854492, 5945.672987985702, 6052.606580572248, 6115.149072927005, 6122.12371750169, 5688.687122208235, 5692.288463942007, 5699.629524080921, 5701.714914254354, 5701.973644743903, 6689.297623749436, 6721.26066132725, 6722.294159113419, 6723.495737819585, 6732.966335168391, 6157.815265542041, 6242.094896240111, 6248.68756562161, 6283.59156654816, 6286.245650521967, 5595.916061259003, 5649.322081350264, 6472.514561407819, 6482.70827812759, 6486.870262594816, 6358.0780786784735, 6358.586691865199, 6359.618422894641, 6377.521685873486, 6379.425851673372, 6421.133147025092, 6425.357464253537, 6438.163038804925, 6441.291457939529, 6457.128287681103, 6127.356627554221, 6162.815115546541, 6261.96869015652, 6263.530235232284, 6269.777393408424, 6232.096346029891, 6237.126802247598, 6248.0, 6249.78125765596, 6251.156093927261, 6122.173506576733, 6129.02260737745, 6133.2428548619655, 6223.0354295084335, 6242.687865606792, 6276.340984196776, 6284.402981716729, 6285.308616052322, 6293.559450838397, 6293.55945083858, 6218.067289906369, 6239.313675495601, 6255.906161407674, 6262.15460912771, 6263.561550691408], "value": 5863.394464731022, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.6631016042783, 792.3225642076025, 813.8046868751497, 875.5534677314567, 888.205056474455, 27731.029413970515, 40817.0, 41547.376789348185, 41558.168836623285, 41651.37522937158, 42344.36483452743, 42482.36276455856, 42508.0, 42562.148757024916, 42602.0, 7326.560406375608, 9179.173874351323, 9328.440293582373, 9337.953310233474, 9355.298352623546, 7345.926540734605, 7426.145993210769, 7435.033445652058, 7439.7396091136825, 11051.447427628627, 11218.102551795877, 11224.214304998653, 11225.214235003554, 11240.718982025433, 11264.324140551593, 11141.610043648474, 11156.776864462665, 11163.776724465546, 11176.21766476347, 11185.944070279678, 7349.706011759523, 7548.698052077911, 10925.344479331265, 10994.780104397947, 11011.0, 39543.8022809887, 40000.59999399998, 40034.59965400338, 40132.79933600313, 42134.78932605348, 3838.846446142151, 3839.2897313997, 3891.805409729502, 3947.822347994347, 4023.517177938637, 26965.460690786076, 27292.90828366863, 28267.57598636022, 28516.144515664357, 29050.854745726137, 3111.144435280316, 3182.3317103408313, 3184.108449634083, 3221.21080335317, 3230.337780754963, 361244.0, 361452.0, 361621.0, 361884.0, 362315.0, 52429.0, 52446.73776631092, 52553.474465255626, 52613.0, 52633.73683131636, 55552.72223638856, 55594.444055558924, 55608.44391556113, 55643.44356556464, 55817.72091139518, 14636.487722929647, 14723.043002204822, 14763.63090922714, 14772.26138693061, 14782.48261310849, 12576.496940122475, 12583.433745481476, 12589.559365422167, 12593.244405335527, 12603.18079324841, 11017.063549598213, 11165.720856978478, 11378.715532111597, 11902.0, 13046.608601741962, 5450.29146210998, 5705.600607957404, 5751.568632352507, 5752.223449834302, 5760.3951585083805, 944.8724422202914, 947.7914858731206, 962.8459446488528, 970.854371844229, 1010.5604062232952, 27824.860875695897, 29188.05961195254, 29367.55948660772, 29656.2585935349, 29792.553111703342, 964.6735738359755, 1095.2880627592128, 1109.9944500277609, 1127.8533790607169, 1157.2709193208225, 13983.161010339412, 14101.153930764187, 14359.78460323096, 14400.423983040771, 14400.639984000483, 295345.0, 304033.0, 323127.0, 323658.0, 324557.3772131124, 42964.92587685271, 51789.7410512945, 52192.47807521952, 52366.21450678243, 52480.950380992166, 1395.7138786548733, 1437.755581551118, 1439.6400899775056, 1442.3076923076983, 1445.6674964757954, 26026.60960085638, 26579.33551661224, 26965.325866853487, 27119.322016949733, 27153.0, 39650.20699585934, 39808.20383592254, 39845.0, 39900.401493977035, 39912.80043599763, 294633.0, 294799.0, 294813.0, 295012.04987950705, 295078.0, 30154.396912062075, 30206.244843879078, 30222.395552088394, 30240.941567045047, 30267.545986809775, 39625.207495850496, 39688.80155599204, 40720.38919416152, 40741.38887916743, 40961.590384095776, 32105.0, 32997.34005319928, 33347.49978750272, 33877.83061084679, 34454.31091378108, 33871.32257354888, 34636.65363346435, 34764.47853282152, 34949.65050349464, 34971.30057398888, 33458.0, 33776.83111584427, 33813.830930845186, 34242.48636270408, 34406.65593344034, 28728.850845966765, 29444.852775735984, 29674.851625741732, 29779.255518612208, 29890.10329690113, 21640.350789476342, 22955.6556651647, 23005.07979680794, 23199.420014499774, 23241.186558470374], "value": 18172.514431003776, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.1332960849265, 403.75572778469024, 421.20182254627474, 421.74905930971073, 442.8516446990258, 11672.649820505389, 20733.170673173074, 21054.684179737316, 21240.89379553104, 21440.785592144075, 21471.785282147175, 21772.237971670995, 22247.1101155954, 22458.0, 22749.4312642184, 6581.144451221335, 6652.035454859055, 6773.7967860964, 6831.829204269883, 6896.0, 6724.5965242085385, 6735.764248251312, 6779.288174741655, 6781.287964763703, 6799.490038247127, 6529.902051469208, 6698.1292431983775, 6753.4259587935085, 6771.661416929134, 6777.356151165632, 6681.832954176136, 6748.0, 6815.625140617264, 6824.488163387766, 6829.078074459959, 6568.113304703852, 6650.800475985729, 6653.334666533308, 6659.500537459661, 6661.3005634408655, 27094.780734866978, 27214.0, 27317.45365092687, 27574.034908778096, 27978.580321295198, 2854.4433835402106, 2867.225849020753, 2873.5976963225166, 2880.135959212249, 2917.7957542972, 15779.211039447982, 16385.91807040957, 16479.34082636693, 16783.748243776354, 16928.984260944384, 1888.2824526679774, 2074.8962551872346, 2203.7796220377836, 2216.4791274050585, 2222.5550396884764, 141322.0, 162547.0, 162873.0, 168836.0, 173502.99999999875, 14894.957352985404, 16283.83716162847, 19986.90006549958, 19992.10035548404, 20408.285710000375, 29601.703982959894, 30333.544996825065, 30998.070057898298, 31284.687153128634, 31557.84221078926, 8382.580870956428, 8747.0, 8873.911260887438, 9756.46339451337, 10090.091891729633, 8269.545175015312, 8651.6971905983, 8660.437071590326, 8709.12908709124, 8783.516906570072, 7889.934858794104, 9182.63269469227, 9443.622255109856, 9520.619175233052, 9525.523723813782, 3960.2475529649273, 4015.9397609035886, 4046.251443482965, 4053.1558141045753, 4061.3298805696727, 847.4152834544145, 855.2473823035689, 863.8963324401112, 879.2438502887512, 882.9779255518534, 19054.523636909187, 20480.078396472196, 21165.89417052905, 21259.68110478344, 21368.145274188857, 890.9465432073966, 904.7376260884402, 906.3383729877227, 923.4274749655257, 930.093159169804, 8986.0, 11010.284331518425, 11085.83371249432, 11283.266587671775, 11371.0, 720945.0, 730592.0, 735189.0, 744914.0, 747891.0, 141504.0, 144927.0, 148531.0, 149123.25438372738, 152976.2351188237, 1170.3855475875234, 1177.5466445418576, 1192.8270400791837, 1203.921745086584, 1210.6852218423098, 18173.0, 19064.95142767161, 19224.519387014876, 19243.0, 19307.324243651405, 53939.190912137135, 54795.0, 64164.679176603815, 65022.69954600976, 66702.33244168741, 64033.0, 75990.0, 91746.08253917376, 92621.61067584126, 93607.53196233975, 12203.572874949334, 12879.0, 12913.418896149507, 13172.736545269232, 13491.718286762742, 14063.718725625635, 14081.647958800702, 14403.063800852911, 14503.1298122113, 14504.419823207376, 11735.589254376056, 11817.881821181678, 11935.403229838646, 12107.394630268274, 12175.14773965814, 10417.583296668048, 11723.941380293043, 11825.408729563316, 12044.879551204376, 12115.333656648969, 10484.737881553023, 11281.32312061279, 11707.648770536898, 12085.697857553632, 12201.450934707782, 12970.740585188432, 12975.156614820007, 12988.935055324662, 13298.734025319633, 13325.0, 11832.230904991147, 11966.700832479257, 12076.879231207575, 12121.27272363661, 12200.901918827349], "value": 11556.835584395298, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1508.132823626415, 1530.2501774130678, 1554.5258696097692, 1563.179330851303, 1568.2394038891139, 10382.377057376554, 10977.670669879917, 10982.890171098297, 10986.945065274682, 11019.393933333664, 10775.892241077607, 11198.440077996109, 11203.887961120387, 11207.271527350716, 11214.10287177026, 2386.6658667786483, 2425.6967879015124, 2427.5509030829353, 2427.6844010278637, 2435.9391015224674, 2361.161787565407, 2383.594788885885, 2395.6406539019113, 2397.3407312988893, 2401.86789726565, 2378.4410663494064, 2400.855948643078, 2410.529946660402, 2411.023535468138, 2412.9396765080837, 2350.4358953851124, 2366.4912043910554, 2376.1802178248477, 2376.3346263046374, 2377.940551486209, 2327.9650805237934, 2332.8483648562787, 2405.290439320405, 2408.963865542018, 2410.3251089694913, 10927.672169834917, 11056.944715276453, 11064.225504214708, 11080.501377438035, 11092.445377731081, 5742.7415766290615, 5920.437558431942, 5923.289205295349, 5930.940690593082, 5947.316058653267, 3378.983105084483, 3470.444728843398, 3479.9478007829903, 3506.68439840413, 3553.129483276588, 971.3006635222629, 1134.8098170530873, 1146.7018575170432, 1166.8133098704166, 1189.9583514576948, 13559.322033898365, 13964.371603277878, 14197.503087391891, 15248.542543723706, 15276.312565934559, 4216.7259128156575, 4340.414044104069, 4341.544137865541, 4344.782760861945, 4510.751908645056, 11173.553057877756, 11179.329240245612, 11188.888111118948, 11231.887681123084, 11267.985881270724, 5582.302212223472, 5587.497125258747, 5589.636673616202, 5596.104623260258, 5603.8599035023635, 4874.780634871439, 5139.511746384088, 5196.220566914993, 5205.531502164823, 5242.1088414969545, 3695.427208782643, 3791.1280405506886, 3795.6773674237998, 3845.576986531479, 3862.0, 907.587047893207, 1030.283952652914, 1035.8808736995343, 1037.6938803053117, 1046.1578429364283, 894.5124906925741, 916.6984964755752, 925.0795458518817, 935.3218916285639, 971.0532231074787, 6419.74321027163, 6469.061986012002, 6469.094326794253, 6472.0, 6511.251206111263, 906.6554709210458, 908.9227415669742, 927.9768005799774, 933.3466573398648, 937.9953100234592, 3056.587360706321, 3065.8773649054233, 3077.261457250244, 3120.0795765249304, 3207.4066297734994, 13463.461461541625, 13967.720645587031, 13968.301584920713, 13978.370973306226, 14026.368813403216, 12567.120301578985, 13165.60503184906, 13717.794233086512, 13875.653108672162, 13894.0, 9896.406215627085, 10167.186625069977, 10171.593136274614, 10177.389356638625, 10184.185265178769, 5810.331811841692, 6470.288268290578, 6481.643509606828, 6613.14029176204, 6689.69896354675, 11608.593699220486, 11738.354390508604, 11862.406879656155, 11978.520859165536, 12008.699782505508, 10234.02776736209, 10531.104856087011, 10683.94658026705, 12350.258984460961, 12386.876131238572, 7816.84366312682, 7901.051873775184, 9989.400635961574, 10093.848592271268, 10170.542325595217, 8775.42959707617, 10390.22073344487, 10510.421926794099, 10522.210834187314, 10525.0, 8777.253933415732, 8993.010768815295, 9126.224270936778, 9193.586288616894, 9322.720318390458, 8407.2433480987, 8506.829863402822, 8896.332775041768, 8906.0, 8939.910600893907, 7643.197464266172, 7799.454038217497, 7815.062192536705, 7822.413319000984, 7823.413244006609, 5704.486596206361, 5733.8566535836935, 5777.2200752897825, 5911.261092363455, 5934.881302373842, 5817.0983497557945, 5855.443732845373, 5967.6121052131475, 6066.817995460143, 6171.1668924694595], "value": 5013.443517132531, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.2878892940701, 365.80063865193466, 381.84916957801687, 383.3406540749911, 385.91509867829075, 4995.325631039808, 5082.923756143656, 5099.821506247277, 5112.590992720583, 5173.6119791015635, 4585.816567337302, 4597.218472859614, 4891.070696567658, 4941.171766446411, 4999.925001124986, 4008.3185858403995, 4113.526944401402, 4180.937285940713, 4183.6653067754505, 4196.370544418339, 3925.9607403926025, 3998.3202855514487, 4101.8974525636795, 4183.602557757008, 4257.063446041866, 3993.760374377532, 4085.9387109193385, 4101.0, 4186.49762028557, 4197.244495990721, 4078.877633670995, 4103.9179216415505, 4120.320147175742, 4122.876313710593, 4174.227767862955, 3506.7194624429985, 3547.9822600887087, 3687.4284485904727, 3842.346801043829, 3857.9421308680394, 4095.610916962884, 4866.902661946777, 4973.651844370896, 5026.472220416877, 5059.013492368989, 4639.909621239006, 4699.906001879944, 4746.644001699887, 4795.472498025214, 4819.481863213076, 4663.251280769711, 5152.896942061137, 5161.561267292284, 5482.862928426782, 5511.0, 3803.861814003458, 3891.6886649068006, 4152.211079894811, 4290.018437186157, 4358.474533913105, 4796.592289655348, 5242.8689282767855, 5369.543588794918, 5486.945130548724, 5511.53151982082, 4219.3333633215325, 4228.682848786353, 4230.5769423057445, 4230.703850730481, 4245.97877010613, 4162.729422587522, 4289.82840686369, 4307.289297265916, 4473.731576105444, 4480.215962206666, 4100.712950093524, 4655.441347038377, 4756.262779269219, 4768.9031522749965, 4851.320815085891, 4753.976230118897, 5009.970961760493, 5327.016870552503, 5369.40936496985, 5378.354597448331, 1504.7065822164573, 1507.7485686879863, 1521.73369660309, 1539.2919257141616, 1545.2814441284856, 1039.968800935958, 1046.6336782126348, 1109.5561775289948, 1116.9106471482262, 1162.7383838636408, 4507.304010711186, 4605.111904119295, 4959.702417854868, 5025.572826309732, 5070.416902056237, 5086.64393492452, 5338.145896656516, 5523.636327301468, 5573.777048918079, 5619.0, 4573.977130114395, 4654.394928659252, 4837.879053023632, 4917.262410638361, 5084.3644544431945, 2457.6436416719835, 2604.5442047641586, 2670.5994100885023, 2855.214815925637, 2887.588863299127, 5233.528982391603, 5513.884655169896, 5591.07747221712, 5731.77072917087, 5786.971065144731, 4966.726830024311, 5008.398992120969, 5212.4266330703595, 5570.6657600544095, 5725.971370143206, 5274.4198138204765, 5373.942673281726, 5789.94210057894, 5794.956907756644, 5796.420357964254, 4810.0, 5626.887462250814, 5632.662040277596, 5642.633228840113, 5660.726336574237, 4752.144613969519, 4922.926156107733, 5112.156494178419, 5393.191021346829, 5397.865053373697, 5047.545720884991, 5069.946671598009, 5414.810481633282, 5488.929658716512, 5624.256480491061, 3780.4518344839294, 3905.902352441212, 3980.980095099506, 4339.609435150852, 4388.407564978687, 4846.684965477232, 5259.2141151551905, 5279.762410691451, 5369.140937449989, 5644.491995720405, 5391.57379179363, 5427.945720542744, 5428.67427954324, 5437.510624043855, 5458.399576046552, 5022.196448568212, 5100.69395836251, 5375.569954403716, 5414.888193604576, 5417.620766546304, 4554.0, 4664.533546645308, 4902.240152776183, 5249.370075590801, 5293.444188360167, 4519.593236608721, 4721.527847215251, 5287.3919499256535, 5333.306670132858, 5336.519713225828, 4948.703077815343, 5032.112957640885, 5191.662541934762, 5250.343707036621, 5327.121025030827], "value": 4103.042796272633, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.4032612605417, 751.7707099334705, 761.6191904047976, 838.4172999765163, 843.0690095180403, 14753.336099875505, 15451.613709657264, 15534.378624854991, 15557.455489057871, 15594.688106237869, 13671.453141874337, 14086.436542538286, 14130.92934535326, 14313.78529322061, 14511.854881451181, 813.6582635293162, 904.1997831918734, 940.539135823445, 944.4380593546829, 944.8110377924443, 916.8533034714447, 934.397313732642, 939.8684184214216, 939.9389039712397, 940.1209868772719, 944.1927152284801, 1003.0120331473493, 1004.3471743366797, 1006.4816619440957, 1007.9798404031915, 996.5067291690588, 1003.6788227767117, 1004.4324956399684, 1005.9497025148713, 1006.3006210683578, 742.8699977503916, 743.680217506472, 743.7173873927927, 743.7619961612286, 743.9256074392572, 15679.764803527956, 15751.661108805765, 15837.920810395874, 15860.52418427449, 15931.681366372724, 13867.23730194839, 13984.230867302294, 14028.929855350658, 14216.075955062888, 14394.064385814992, 11174.44127793607, 11604.535818567247, 11612.645856188325, 11732.824007639892, 11901.345426001566, 3374.460086386191, 3526.6297038811067, 3625.7643253188457, 3702.740808143431, 3817.8472861085525, 102495.8502489853, 109276.0, 109746.0, 110608.0, 111150.44424777824, 10202.387856728472, 10383.84424233637, 10673.573057077785, 10791.83812242817, 10802.0, 22352.888235559043, 22591.887040564692, 22596.774032259797, 22639.320820375415, 22661.43346416353, 7687.077550693841, 7701.075870895528, 7776.144624091346, 7828.334591559781, 7967.521948683097, 4267.722598031118, 4278.251306021434, 4370.519242883281, 4380.802863871133, 4556.974680696817, 3462.6018007928906, 3564.7682900611376, 3565.5899571549085, 3565.6969157621375, 3568.5896121946307, 514.747339109177, 616.3035769580346, 619.7520991603395, 622.2595111816923, 623.1525125828869, 623.5215020294294, 667.442685357722, 671.4628297362091, 711.3064761857252, 750.2572453271222, 8387.287080598095, 9635.0, 9864.605415783288, 10134.037266459674, 10362.083014642316, 639.8464368551607, 643.4948565376158, 661.3915198017835, 667.1678723817427, 695.544418405945, 4791.928121078186, 4858.368412106403, 4892.19278818998, 4898.167311556973, 4908.730019848943, 91156.0, 94074.0, 94195.9353048524, 95834.64578739711, 97280.51359743156, 78468.0, 79960.0, 82565.58717206375, 86907.0, 90571.54714226519, 64554.70890582162, 67412.662936685, 73497.2650273501, 79427.60286198532, 83661.0, 11023.489015766727, 11023.503942322775, 11180.776384472427, 11324.37715925632, 11387.0, 39994.40008399818, 49188.50811491839, 49726.751366242934, 50536.0, 50607.49392506028, 54347.0, 54711.45288547223, 64011.0, 64913.026304606414, 65761.67119164566, 12979.351032448529, 13818.861811381757, 13837.10058846212, 14248.715025699636, 14368.712625747217, 14269.500567480089, 14304.713905722036, 14582.270886455422, 15029.473968411054, 15150.3182356792, 12294.016478681506, 12933.595176300085, 13823.377947992565, 14240.643983900485, 14313.0, 12849.293288869201, 12957.676058098623, 13194.406251718503, 13419.329033548478, 13459.46162153542, 13358.666033348854, 13642.386092625657, 13644.727105458034, 13711.108777929403, 13858.86141138616, 10078.344907580984, 11110.0, 11133.165012623924, 11899.226550274205, 12002.459889305177, 11443.097924502046, 11513.769724605294, 11635.47640356169, 11647.534098636299, 11698.239614425034], "value": 7592.427977808811, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1445.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 116.0, 115.0, 116.0, 116.0, 115.0, 114.0, 115.0, 117.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 107.0, 109.0, 140.0, 110.0, 110.0, 112.0, 109.0, 128.0, 110.0], "value": 117.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 187.0, 185.0, 186.0, 187.0, 186.0, 184.0, 185.0, 187.0, 185.0], "value": 186.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 61.0, 88.0, 52.0, 77.0, 80.0, 69.0, 75.0, 59.0], "value": 70.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 68.0, 112.0, 97.0, 96.0, 75.0, 83.0, 68.0, 68.0, 84.0], "value": 83.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 101.0, 131.0, 89.0, 90.0, 90.0, 89.0, 91.0, 91.0], "value": 95.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 99.0, 96.0, 95.0, 96.0, 99.0, 97.0, 96.0], "value": 96.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 133.0, 89.0, 91.0, 84.0, 83.0, 86.0, 84.0, 84.0, 87.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 60.0, 62.0, 66.0, 64.0, 63.0, 64.0, 64.0, 65.0], "value": 63.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 68.0, 66.0, 84.0, 78.0, 79.0, 57.0, 58.0, 57.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 84.0, 84.0, 86.0, 82.0, 81.0, 85.0, 84.0, 99.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 137.0, 137.0, 137.0, 135.0, 145.0, 134.0, 114.0, 192.0, 147.0], "value": 141.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 163.0, 165.0, 167.0, 165.0, 165.0, 163.0, 163.0, 164.0, 163.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 63.0, 64.0, 64.0, 65.0, 64.0, 65.0, 64.0, 61.0, 64.0], "value": 63.9, "unit": "score"}]}]} 18:26:21 INFO - Return code: 0 18:26:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:26:21 ERROR - # TBPL SUCCESS # 18:26:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:26:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:26:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:26:21 INFO - 2017-03-14 18:26:21.371 screenresolution[1654:10733] starting screenresolution argv=screenresolution get 18:26:21 INFO - 2017-03-14 18:26:21.374 screenresolution[1654:10733] Display 0: 1600x1200x32@60 18:26:21 INFO - 2017-03-14 18:26:21.380 screenresolution[1655:10736] starting screenresolution argv=screenresolution list 18:26:21 INFO - Available Modes on Display 0 18:26:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:26:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:26:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:26:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:26:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:26:21 INFO - Intel Iris: 18:26:21 INFO - Chipset Model: Intel Iris 18:26:21 INFO - Type: GPU 18:26:21 INFO - Bus: Built-In 18:26:21 INFO - VRAM (Dynamic, Max): 1536 MB 18:26:21 INFO - Vendor: Intel (0x8086) 18:26:21 INFO - Device ID: 0x0a2e 18:26:21 INFO - Revision ID: 0x0009 18:26:21 INFO - Displays: 18:26:21 INFO - Display: 18:26:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:26:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:26:21 INFO - Display Serial Number: 1600x1200 60 18:26:21 INFO - Main Display: Yes 18:26:21 INFO - Mirror: Off 18:26:21 INFO - Online: Yes 18:26:21 INFO - Rotation: Supported 18:26:21 INFO - Return code: 0 18:26:21 INFO - Running post-action listener: _resource_record_post_action 18:26:21 INFO - [mozharness: 2017-03-15 01:26:21.614478Z] Finished run-tests step (success) 18:26:21 INFO - Running post-run listener: _resource_record_post_run 18:26:21 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 34671616; Write bytes: 507873792; Read time: 331; Write time: 1538 18:26:21 INFO - TinderboxPrint: CPU usage
54.1% 18:26:21 INFO - TinderboxPrint: I/O read bytes / time
34,671,616 / 331 18:26:21 INFO - TinderboxPrint: I/O write bytes / time
507,873,792 / 1,538 18:26:21 INFO - TinderboxPrint: CPU idle
2,204.0 (45.9%) 18:26:21 INFO - TinderboxPrint: CPU system
963.6 (20.1%) 18:26:21 INFO - TinderboxPrint: CPU user
1,638.0 (34.1%) 18:26:21 INFO - TinderboxPrint: Swap in / out
238,145,536 / 0 18:26:21 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 202111488; Write bytes: 201226240; Read time: 15595; Write time: 403 18:26:21 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 32816128; Write bytes: 295891456; Read time: 309; Write time: 1105 18:26:22 INFO - Running post-run listener: _upload_blobber_files 18:26:22 INFO - Blob upload gear active. 18:26:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:26:22 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 18:26:22 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:26:22 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:26:22 INFO - (blobuploader) - INFO - Open directory for files ... 18:26:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:26:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:26:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:26:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:26:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:26:23 INFO - (blobuploader) - INFO - Done attempting. 18:26:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:26:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:26:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:26:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:26:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:26:24 INFO - (blobuploader) - INFO - Done attempting. 18:26:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:26:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:26:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:26:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:26:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:26:25 INFO - (blobuploader) - INFO - Done attempting. 18:26:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:26:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:26:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:26:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:26:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:26:27 INFO - (blobuploader) - INFO - Done attempting. 18:26:27 INFO - (blobuploader) - INFO - Iteration through files over. 18:26:27 INFO - Return code: 0 18:26:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:26:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:26:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b407fc47220b7736d8cc655f2e686deed56ef74b7381f844f707b0abbb3a43a3369dee5e4dea760c53f9d1871b3113194bcf853fd89adc6a0879d6b89d3d2d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7095decb9d329d13f0ba67db7b2968005193c8283046fc2266be8a7f37b8535776bb4aa93295e3ef3f5eb5f127f3cb949b632af473eae6d401762d4c1187919e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d739820720ac51699c78d95ba540c3188b6674c1c8fa8b18361fc4623aeea0d5dd030ab357ad9a546cf524b32142184af603ca4433864f8b36242b9c38b351d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0649ffd0c3fbab132099450e2299c72859dc31f6856f00494782cb219678f9a28ac7f9e3249448cd59cf33c667edcd7cf117f3da2eb72728851338430b8e83a7"} 18:26:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:26:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:26:27 INFO - Contents: 18:26:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b407fc47220b7736d8cc655f2e686deed56ef74b7381f844f707b0abbb3a43a3369dee5e4dea760c53f9d1871b3113194bcf853fd89adc6a0879d6b89d3d2d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7095decb9d329d13f0ba67db7b2968005193c8283046fc2266be8a7f37b8535776bb4aa93295e3ef3f5eb5f127f3cb949b632af473eae6d401762d4c1187919e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d739820720ac51699c78d95ba540c3188b6674c1c8fa8b18361fc4623aeea0d5dd030ab357ad9a546cf524b32142184af603ca4433864f8b36242b9c38b351d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0649ffd0c3fbab132099450e2299c72859dc31f6856f00494782cb219678f9a28ac7f9e3249448cd59cf33c667edcd7cf117f3da2eb72728851338430b8e83a7"} 18:26:27 INFO - Running post-run listener: copy_logs_to_upload_dir 18:26:27 INFO - Copying logs to upload dir... 18:26:27 INFO - mkdir: /builds/slave/test/build/upload/logs 18:26:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1375.674723 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 55 secs) (at 2017-03-14 18:26:27.752734) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-14 18:26:27.755835) ========= 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.pEWu1u96Iy/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.hh9EEjaB4u/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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/Mozilla-Aurora/sha512/4b407fc47220b7736d8cc655f2e686deed56ef74b7381f844f707b0abbb3a43a3369dee5e4dea760c53f9d1871b3113194bcf853fd89adc6a0879d6b89d3d2d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7095decb9d329d13f0ba67db7b2968005193c8283046fc2266be8a7f37b8535776bb4aa93295e3ef3f5eb5f127f3cb949b632af473eae6d401762d4c1187919e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d739820720ac51699c78d95ba540c3188b6674c1c8fa8b18361fc4623aeea0d5dd030ab357ad9a546cf524b32142184af603ca4433864f8b36242b9c38b351d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0649ffd0c3fbab132099450e2299c72859dc31f6856f00494782cb219678f9a28ac7f9e3249448cd59cf33c667edcd7cf117f3da2eb72728851338430b8e83a7"} build_url:https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011251 build_url: 'https://queue.taskcluster.net/v1/task/Rc0rpY9-R_CokWcZUidK1w/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4b407fc47220b7736d8cc655f2e686deed56ef74b7381f844f707b0abbb3a43a3369dee5e4dea760c53f9d1871b3113194bcf853fd89adc6a0879d6b89d3d2d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7095decb9d329d13f0ba67db7b2968005193c8283046fc2266be8a7f37b8535776bb4aa93295e3ef3f5eb5f127f3cb949b632af473eae6d401762d4c1187919e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d739820720ac51699c78d95ba540c3188b6674c1c8fa8b18361fc4623aeea0d5dd030ab357ad9a546cf524b32142184af603ca4433864f8b36242b9c38b351d0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0649ffd0c3fbab132099450e2299c72859dc31f6856f00494782cb219678f9a28ac7f9e3249448cd59cf33c667edcd7cf117f3da2eb72728851338430b8e83a7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-14 18:26:27.790113) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 18:26:27.790454) ========= 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.pEWu1u96Iy/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.hh9EEjaB4u/Listeners TMPDIR=/var/folders/jd/1y99yyv51vb4ndkbm8frkkx800000w/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.005282 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-14 18:26:27.840124) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-14 18:26:27.840562) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-14 18:26:27.841045) ========= ========= Total master_lag: 0.18 =========