builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0005 starttime: 1478650199.85 results: success (0) buildid: 20161108135923 builduid: 0c8ee28ae1824dc1b8db55032bc5d0b4 revision: 783356f1476eafd8e4d6fa5f3919cf6167e84f8d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-08 16:09:59.849994) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-08 16:09:59.850433) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-08 16:09:59.850707) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-08 16:09:59.875476) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 16:09:59.875819) ========= 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.3afePF6PdJ/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.ObcnyCNFJF/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 --2016-11-08 16:09:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.58M=0.001s 2016-11-08 16:09:59 (9.58 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.086556 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 16:09:59.984920) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 16:09:59.985232) ========= 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.3afePF6PdJ/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.ObcnyCNFJF/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.028410 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 16:10:00.034340) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-11-08 16:10:00.034783) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 783356f1476eafd8e4d6fa5f3919cf6167e84f8d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 783356f1476eafd8e4d6fa5f3919cf6167e84f8d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3afePF6PdJ/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.ObcnyCNFJF/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 2016-11-08 16:10:00,111 truncating revision to first 12 chars 2016-11-08 16:10:00,111 Setting DEBUG logging. 2016-11-08 16:10:00,111 attempt 1/10 2016-11-08 16:10:00,111 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/783356f1476e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-08 16:10:01,139 unpacking tar archive at: mozilla-central-783356f1476e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.299247 ========= master_lag: 1.77 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-11-08 16:10:03.104701) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-08 16:10:03.105033) ========= script_repo_revision: 783356f1476eafd8e4d6fa5f3919cf6167e84f8d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-08 16:10:03.105454) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-08 16:10:03.105721) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-08 16:10:03.126443) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-08 16:10:03.126750) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3afePF6PdJ/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.ObcnyCNFJF/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 16:10:03 INFO - MultiFileLogger online at 20161108 16:10:03 in /builds/slave/test 16:10:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:10:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:10:03 INFO - {'append_to_log': False, 16:10:03 INFO - 'base_work_dir': '/builds/slave/test', 16:10:03 INFO - 'blob_upload_branch': 'Firefox', 16:10:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:10:03 INFO - 'branch': 'Firefox', 16:10:03 INFO - 'buildbot_json_path': 'buildprops.json', 16:10:03 INFO - 'config_files': ('talos/mac_config.py',), 16:10:03 INFO - 'default_actions': ('clobber', 16:10:03 INFO - 'read-buildbot-config', 16:10:03 INFO - 'download-and-extract', 16:10:03 INFO - 'populate-webroot', 16:10:03 INFO - 'create-virtualenv', 16:10:03 INFO - 'install', 16:10:03 INFO - 'run-tests'), 16:10:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:10:03 INFO - 'download_minidump_stackwalk': True, 16:10:03 INFO - 'download_symbols': 'ondemand', 16:10:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:10:03 INFO - 'tooltool.py': '/tools/tooltool.py', 16:10:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:10:03 INFO - '/tools/misc-python/virtualenv.py')}, 16:10:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:10:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:10:03 INFO - 'installer_path': 'installer.exe', 16:10:03 INFO - 'log_level': 'info', 16:10:03 INFO - 'log_name': 'talos', 16:10:03 INFO - 'log_to_console': True, 16:10:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:10:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:10:03 INFO - 'opt_config_files': (), 16:10:03 INFO - 'pip_index': False, 16:10:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:10:03 INFO - 'cmd': ('bash', 16:10:03 INFO - '-c', 16:10:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:10:03 INFO - 'enabled': True, 16:10:03 INFO - 'halt_on_failure': False, 16:10:03 INFO - 'name': 'check_screen_resolution'},), 16:10:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:10:03 INFO - 'cmd': ('bash', 16:10:03 INFO - '-c', 16:10:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:10:03 INFO - 'enabled': True, 16:10:03 INFO - 'halt_on_failure': False, 16:10:03 INFO - 'name': 'check_screen_resolution'},), 16:10:03 INFO - 'run_cmd_checks_enabled': True, 16:10:03 INFO - 'sps_profile': False, 16:10:03 INFO - 'sps_profile_interval': 0, 16:10:03 INFO - 'suite': 'dromaeojs', 16:10:03 INFO - 'system_bits': '32', 16:10:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:10:03 INFO - 'title': 't-yosemite-r7-0005', 16:10:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:10:03 INFO - 'use_talos_json': True, 16:10:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:10:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:10:03 INFO - 'work_dir': 'build'} 16:10:03 INFO - [mozharness: 2016-11-09 00:10:03.335184Z] Running clobber step. 16:10:03 INFO - Running pre-action listener: _resource_record_pre_action 16:10:03 INFO - Running main action method: clobber 16:10:03 INFO - rmtree: /builds/slave/test/build 16:10:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:10:05 INFO - Running post-action listener: _resource_record_post_action 16:10:05 INFO - [mozharness: 2016-11-09 00:10:05.760972Z] Finished clobber step (success) 16:10:05 INFO - [mozharness: 2016-11-09 00:10:05.761113Z] Running read-buildbot-config step. 16:10:05 INFO - Running pre-action listener: _resource_record_pre_action 16:10:05 INFO - Running main action method: read_buildbot_config 16:10:05 INFO - Using buildbot properties: 16:10:05 INFO - { 16:10:05 INFO - "project": "", 16:10:05 INFO - "product": "firefox", 16:10:05 INFO - "script_repo_revision": "production", 16:10:05 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 16:10:05 INFO - "repository": "", 16:10:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 16:10:05 INFO - "buildid": "20161108135923", 16:10:05 INFO - "pgo_build": "False", 16:10:05 INFO - "basedir": "/builds/slave/test", 16:10:05 INFO - "buildnumber": 135, 16:10:05 INFO - "slavename": "t-yosemite-r7-0005", 16:10:05 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 16:10:05 INFO - "platform": "yosemite_r7", 16:10:05 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:10:05 INFO - "branch": "mozilla-central", 16:10:05 INFO - "repo_path": "mozilla-central", 16:10:05 INFO - "revision": "783356f1476eafd8e4d6fa5f3919cf6167e84f8d", 16:10:05 INFO - "stage_platform": "macosx64", 16:10:05 INFO - "builduid": "0c8ee28ae1824dc1b8db55032bc5d0b4", 16:10:05 INFO - "slavebuilddir": "test" 16:10:05 INFO - } 16:10:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 16:10:05 INFO - Running post-action listener: _resource_record_post_action 16:10:05 INFO - [mozharness: 2016-11-09 00:10:05.764544Z] Finished read-buildbot-config step (success) 16:10:05 INFO - [mozharness: 2016-11-09 00:10:05.764666Z] Running download-and-extract step. 16:10:05 INFO - Running pre-action listener: _resource_record_pre_action 16:10:05 INFO - Running main action method: download_and_extract 16:10:05 INFO - mkdir: /builds/slave/test/build/tests 16:10:05 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')]} 16:10:05 INFO - trying https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 16:10:05 INFO - Downloading https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 16:10:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 16:10:06 INFO - Downloaded 1199 bytes. 16:10:06 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 16:10:06 INFO - Using the following test package requirements: 16:10:06 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:10:06 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:10:06 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 16:10:06 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:10:06 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 16:10:06 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:10:06 INFO - u'jsshell-mac.zip'], 16:10:06 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:10:06 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 16:10:06 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:10:06 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:10:06 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 16:10:06 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:10:06 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 16:10:06 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:10:06 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 16:10:06 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:10:06 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 16:10:06 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:10:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:10:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:10:06 INFO - Fetch https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:10:07 INFO - Http code: 200 16:10:07 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 16:10:07 INFO - x-amz-cf-id: k7m5KynWGw1EZ7eOIU2GQhl4kjPYDUxW6yyBfg7PF6gmoN62oeI0ow== 16:10:07 INFO - x-amz-version-id: nqQ0UdZr5C2z_2vSlTazWiE2MF2MLNlp 16:10:07 INFO - Expected file size: 16318130 16:10:07 INFO - Obtained file size: 16318130 16:10:14 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:10:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:10:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:10:14 INFO - Fetch https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:10:15 INFO - Http code: 200 16:10:15 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 16:10:15 INFO - x-amz-cf-id: nc_pAPZ57xN2rOj3J7hll2Aldb7J67B3q5009LQzRg5ZbCUA6ZqNLg== 16:10:15 INFO - x-amz-version-id: nqQ0UdZr5C2z_2vSlTazWiE2MF2MLNlp 16:10:15 INFO - Expected file size: 16318130 16:10:15 INFO - Obtained file size: 16318130 16:10:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 16:10:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:10:21 INFO - Fetch https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 16:10:22 INFO - Http code: 200 16:10:22 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 16:10:22 INFO - x-amz-cf-id: ykDlQghmrfy-54S_M9PshryL9CTgeLQ-baqhSU7hmbCoUGH11u5wpw== 16:10:22 INFO - x-amz-version-id: .2wxVRm_QZlUsLKyKHwbRf1vfvvdXYbC 16:10:22 INFO - Expected file size: 14409038 16:10:22 INFO - Obtained file size: 14409038 16:10:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:22 INFO - trying https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:10:22 INFO - Downloading https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 16:10:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 16:10:25 INFO - Downloaded 111570876 bytes. 16:10:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:10:25 INFO - mkdir: /builds/slave/test/properties 16:10:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:10:25 INFO - Writing to file /builds/slave/test/properties/build_url 16:10:25 INFO - Contents: 16:10:25 INFO - build_url:https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:10:26 INFO - Running post-action listener: _resource_record_post_action 16:10:26 INFO - Running post-action listener: set_extra_try_arguments 16:10:26 INFO - [mozharness: 2016-11-09 00:10:26.352892Z] Finished download-and-extract step (success) 16:10:26 INFO - [mozharness: 2016-11-09 00:10:26.353012Z] Running populate-webroot step. 16:10:26 INFO - Running pre-action listener: _resource_record_pre_action 16:10:26 INFO - Running main action method: populate_webroot 16:10:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:10:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:10:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:10:26 INFO - '--tppagecycles', 16:10:26 INFO - '7'], 16:10:26 INFO - 'tests': ['tsvgm']}}, 16:10:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:10:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:10:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:10:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:10:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:10:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:10:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:26 INFO - 'talos_options': ['--disable-e10s'], 16:10:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:10:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:10:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:26 INFO - 'talos_options': ['--disable-e10s'], 16:10:26 INFO - 'tests': ['damp', 'tps']}, 16:10:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:26 INFO - 'tests': ['damp', 'tps']}, 16:10:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:10:26 INFO - 'tests': ['dromaeo_dom']}, 16:10:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:10:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:10:26 INFO - 'tests': ['basic_compositor_video']}, 16:10:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:10:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:10:26 INFO - 'tests': ['a11yr', 16:10:26 INFO - 'ts_paint', 16:10:26 INFO - 'tpaint', 16:10:26 INFO - 'sessionrestore', 16:10:26 INFO - 'sessionrestore_no_auto_restore', 16:10:26 INFO - 'tabpaint']}, 16:10:26 INFO - 'other-e10s': {'tests': ['a11yr', 16:10:26 INFO - 'ts_paint', 16:10:26 INFO - 'tpaint', 16:10:26 INFO - 'sessionrestore', 16:10:26 INFO - 'sessionrestore_no_auto_restore', 16:10:26 INFO - 'tabpaint']}, 16:10:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:10:26 INFO - 'ts_paint', 16:10:26 INFO - 'tpaint', 16:10:26 INFO - 'sessionrestore', 16:10:26 INFO - 'sessionrestore_no_auto_restore', 16:10:26 INFO - 'tabpaint']}, 16:10:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:10:26 INFO - 'ts_paint', 16:10:26 INFO - 'tpaint', 16:10:26 INFO - 'sessionrestore', 16:10:26 INFO - 'sessionrestore_no_auto_restore', 16:10:26 INFO - 'tabpaint']}, 16:10:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:10:26 INFO - 'tests': ['a11yr', 16:10:26 INFO - 'ts_paint', 16:10:26 INFO - 'tpaint', 16:10:26 INFO - 'sessionrestore', 16:10:26 INFO - 'sessionrestore_no_auto_restore', 16:10:26 INFO - 'tabpaint']}, 16:10:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:10:26 INFO - 'tests': ['a11yr', 16:10:26 INFO - 'ts_paint', 16:10:26 INFO - 'tpaint', 16:10:26 INFO - 'sessionrestore', 16:10:26 INFO - 'sessionrestore_no_auto_restore', 16:10:26 INFO - 'tabpaint']}, 16:10:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:10:26 INFO - 'tests': ['tsvgx', 16:10:26 INFO - 'tsvgr_opacity', 16:10:26 INFO - 'tart', 16:10:26 INFO - 'tscrollx', 16:10:26 INFO - 'cart']}, 16:10:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:10:26 INFO - 'tsvgr_opacity', 16:10:26 INFO - 'tart', 16:10:26 INFO - 'tscrollx', 16:10:26 INFO - 'cart']}, 16:10:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:26 INFO - 'talos_options': ['--disable-e10s'], 16:10:26 INFO - 'tests': ['tp5o']}, 16:10:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:26 INFO - 'tests': ['tp5o']}, 16:10:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:26 INFO - 'talos_options': ['--disable-e10s', 16:10:26 INFO - '--xperf_path', 16:10:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:10:26 INFO - 'tests': ['tp5n']}, 16:10:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:26 INFO - 'talos_options': ['--xperf_path', 16:10:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:10:26 INFO - 'tests': ['tp5n']}}, 16:10:26 INFO - 'talos.zip': {'path': '', 16:10:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:10:26 INFO - Running post-action listener: _resource_record_post_action 16:10:26 INFO - [mozharness: 2016-11-09 00:10:26.371666Z] Finished populate-webroot step (success) 16:10:26 INFO - [mozharness: 2016-11-09 00:10:26.371797Z] Running create-virtualenv step. 16:10:26 INFO - Running pre-action listener: _resource_record_pre_action 16:10:26 INFO - Running main action method: create_virtualenv 16:10:26 INFO - Creating virtualenv /builds/slave/test/build/venv 16:10:26 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 16:10:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:10:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:10:26 INFO - Using real prefix '/tools/python27' 16:10:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:10:27 INFO - Installing distribute.............................................................................................................................................................................................done. 16:10:30 INFO - Installing pip.................done. 16:10:30 INFO - Return code: 0 16:10:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:10:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:10:30 INFO - Reading from file tmpfile_stdout 16:10:30 INFO - Output received: 16:10:30 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) 16:10:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:10:30 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')]} 16:10:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:30 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')]} 16:10:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:30 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 0x1101f4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11016a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe1a48bbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110008d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110000b20>, '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.3afePF6PdJ/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.ObcnyCNFJF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 16:10:30 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 16:10:30 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 16:10:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3afePF6PdJ/Render', 16:10:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:30 INFO - 'HOME': '/Users/cltbld', 16:10:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:30 INFO - 'LOGNAME': 'cltbld', 16:10:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:30 INFO - 'MOZ_NO_REMOTE': '1', 16:10:30 INFO - 'NO_EM_RESTART': '1', 16:10:30 INFO - 'PAGER': '/bin/cat', 16:10:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:30 INFO - 'PWD': '/builds/slave/test', 16:10:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:30 INFO - 'SHELL': '/bin/bash', 16:10:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObcnyCNFJF/Listeners', 16:10:30 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 16:10:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:30 INFO - 'USER': 'cltbld', 16:10:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:30 INFO - 'XPC_FLAGS': '0x0', 16:10:30 INFO - 'XPC_SERVICE_NAME': '0', 16:10:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:30 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 16:10:30 INFO - Cleaning up... 16:10:30 INFO - Return code: 0 16:10:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:10:30 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')]} 16:10:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:30 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')]} 16:10:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:30 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 0x1101f4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11016a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe1a48bbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110008d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110000b20>, '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.3afePF6PdJ/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.ObcnyCNFJF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 16:10:30 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 16:10:30 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 16:10:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3afePF6PdJ/Render', 16:10:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:30 INFO - 'HOME': '/Users/cltbld', 16:10:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:30 INFO - 'LOGNAME': 'cltbld', 16:10:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:30 INFO - 'MOZ_NO_REMOTE': '1', 16:10:30 INFO - 'NO_EM_RESTART': '1', 16:10:30 INFO - 'PAGER': '/bin/cat', 16:10:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:30 INFO - 'PWD': '/builds/slave/test', 16:10:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:30 INFO - 'SHELL': '/bin/bash', 16:10:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObcnyCNFJF/Listeners', 16:10:30 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 16:10:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:30 INFO - 'USER': 'cltbld', 16:10:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:30 INFO - 'XPC_FLAGS': '0x0', 16:10:30 INFO - 'XPC_SERVICE_NAME': '0', 16:10:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:30 INFO - Downloading/unpacking psutil>=3.1.1 16:10:30 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 16:10:30 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 16:10:30 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 16:10:30 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 16:10:30 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 16:10:30 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 16:10:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:10:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:10:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:10:34 INFO - Installing collected packages: psutil 16:10:34 INFO - Running setup.py install for psutil 16:10:34 INFO - building 'psutil._psutil_osx' extension 16:10:34 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 16:10:35 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 16:10:35 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 16:10:35 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 16:10:35 INFO - building 'psutil._psutil_posix' extension 16:10:35 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 16:10:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:10:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:10:35 INFO - ^ 16:10:35 INFO - 1 warning generated. 16:10:35 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 16:10:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:10:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:10:35 INFO - Successfully installed psutil 16:10:35 INFO - Cleaning up... 16:10:35 INFO - Return code: 0 16:10:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:10:35 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')]} 16:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:35 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')]} 16:10:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:35 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 0x1101f4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11016a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe1a48bbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110008d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110000b20>, '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.3afePF6PdJ/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.ObcnyCNFJF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 16:10:35 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 16:10:35 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 16:10:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3afePF6PdJ/Render', 16:10:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:35 INFO - 'HOME': '/Users/cltbld', 16:10:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:35 INFO - 'LOGNAME': 'cltbld', 16:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:35 INFO - 'MOZ_NO_REMOTE': '1', 16:10:35 INFO - 'NO_EM_RESTART': '1', 16:10:35 INFO - 'PAGER': '/bin/cat', 16:10:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:35 INFO - 'PWD': '/builds/slave/test', 16:10:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:35 INFO - 'SHELL': '/bin/bash', 16:10:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObcnyCNFJF/Listeners', 16:10:35 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 16:10:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:35 INFO - 'USER': 'cltbld', 16:10:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:35 INFO - 'XPC_FLAGS': '0x0', 16:10:35 INFO - 'XPC_SERVICE_NAME': '0', 16:10:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:10:35 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 16:10:35 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 16:10:35 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 16:10:35 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 16:10:35 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 16:10:35 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 16:10:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:10:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:10:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:10:38 INFO - Installing collected packages: mozsystemmonitor 16:10:38 INFO - Running setup.py install for mozsystemmonitor 16:10:38 INFO - Successfully installed mozsystemmonitor 16:10:38 INFO - Cleaning up... 16:10:38 INFO - Return code: 0 16:10:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:38 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 0x1101f4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11016a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe1a48bbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110008d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110000b20>, '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.3afePF6PdJ/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.ObcnyCNFJF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 16:10:38 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 16:10:38 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 16:10:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3afePF6PdJ/Render', 16:10:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:38 INFO - 'HOME': '/Users/cltbld', 16:10:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:38 INFO - 'LOGNAME': 'cltbld', 16:10:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:38 INFO - 'MOZ_NO_REMOTE': '1', 16:10:38 INFO - 'NO_EM_RESTART': '1', 16:10:38 INFO - 'PAGER': '/bin/cat', 16:10:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:38 INFO - 'PWD': '/builds/slave/test', 16:10:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:38 INFO - 'SHELL': '/bin/bash', 16:10:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObcnyCNFJF/Listeners', 16:10:38 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 16:10:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:38 INFO - 'USER': 'cltbld', 16:10:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:38 INFO - 'XPC_FLAGS': '0x0', 16:10:38 INFO - 'XPC_SERVICE_NAME': '0', 16:10:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:38 INFO - Downloading/unpacking jsonschema==2.5.1 16:10:38 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 16:10:38 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 16:10:38 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 16:10:38 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 16:10:38 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 16:10:38 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 16:10:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:10:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:10:41 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 16:10:41 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 16:10:41 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 16:10:41 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 16:10:41 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 16:10:41 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 16:10:41 INFO - Downloading functools32-3.2.3-2.tar.gz 16:10:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:10:41 INFO - warning: no files found matching '*.txt' 16:10:41 INFO - no previously-included directories found matching 'build' 16:10:41 INFO - no previously-included directories found matching 'dist' 16:10:41 INFO - no previously-included directories found matching '.git*' 16:10:41 INFO - Installing collected packages: jsonschema, functools32 16:10:41 INFO - Running setup.py install for functools32 16:10:41 INFO - warning: no files found matching '*.txt' 16:10:41 INFO - no previously-included directories found matching 'build' 16:10:41 INFO - no previously-included directories found matching 'dist' 16:10:41 INFO - no previously-included directories found matching '.git*' 16:10:41 INFO - Successfully installed jsonschema functools32 16:10:41 INFO - Cleaning up... 16:10:42 INFO - Return code: 0 16:10:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:10:42 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')]} 16:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:42 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')]} 16:10:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:42 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 0x1101f4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11016a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe1a48bbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110008d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110000b20>, '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.3afePF6PdJ/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.ObcnyCNFJF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 16:10:42 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 16:10:42 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 16:10:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3afePF6PdJ/Render', 16:10:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:42 INFO - 'HOME': '/Users/cltbld', 16:10:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:42 INFO - 'LOGNAME': 'cltbld', 16:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:42 INFO - 'MOZ_NO_REMOTE': '1', 16:10:42 INFO - 'NO_EM_RESTART': '1', 16:10:42 INFO - 'PAGER': '/bin/cat', 16:10:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:42 INFO - 'PWD': '/builds/slave/test', 16:10:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:42 INFO - 'SHELL': '/bin/bash', 16:10:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObcnyCNFJF/Listeners', 16:10:42 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 16:10:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:42 INFO - 'USER': 'cltbld', 16:10:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:42 INFO - 'XPC_FLAGS': '0x0', 16:10:42 INFO - 'XPC_SERVICE_NAME': '0', 16:10:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:10:42 INFO - Cleaning up... 16:10:42 INFO - Return code: 0 16:10:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:10:42 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')]} 16:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:42 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')]} 16:10:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:42 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 0x1101f4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11016a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe1a48bbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110008d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110000b20>, '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.3afePF6PdJ/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.ObcnyCNFJF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 16:10:42 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 16:10:42 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 16:10:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3afePF6PdJ/Render', 16:10:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:42 INFO - 'HOME': '/Users/cltbld', 16:10:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:42 INFO - 'LOGNAME': 'cltbld', 16:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:42 INFO - 'MOZ_NO_REMOTE': '1', 16:10:42 INFO - 'NO_EM_RESTART': '1', 16:10:42 INFO - 'PAGER': '/bin/cat', 16:10:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:42 INFO - 'PWD': '/builds/slave/test', 16:10:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:42 INFO - 'SHELL': '/bin/bash', 16:10:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObcnyCNFJF/Listeners', 16:10:42 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 16:10:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:42 INFO - 'USER': 'cltbld', 16:10:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:42 INFO - 'XPC_FLAGS': '0x0', 16:10:42 INFO - 'XPC_SERVICE_NAME': '0', 16:10:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:42 INFO - Downloading/unpacking blobuploader==1.2.4 16:10:42 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 16:10:42 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 16:10:42 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 16:10:42 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 16:10:42 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 16:10:42 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 16:10:45 INFO - Downloading blobuploader-1.2.4.tar.gz 16:10:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:10:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:10:45 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 16:10:45 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 16:10:45 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 16:10:45 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 16:10:45 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 16:10:45 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 16:10:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:10:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:10:45 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 16:10:45 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 16:10:45 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 16:10:45 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 16:10:45 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 16:10:45 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 16:10:46 INFO - Downloading docopt-0.6.1.tar.gz 16:10:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:10:46 INFO - Installing collected packages: blobuploader, requests, docopt 16:10:46 INFO - Running setup.py install for blobuploader 16:10:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:10:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:10:46 INFO - Running setup.py install for requests 16:10:47 INFO - Running setup.py install for docopt 16:10:47 INFO - Successfully installed blobuploader requests docopt 16:10:47 INFO - Cleaning up... 16:10:47 INFO - Return code: 0 16:10:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:10:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:47 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 0x1101f4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11016a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe1a48bbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110008d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110000b20>, '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.3afePF6PdJ/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.ObcnyCNFJF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 16:10:47 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 16:10:47 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 16:10:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3afePF6PdJ/Render', 16:10:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:47 INFO - 'HOME': '/Users/cltbld', 16:10:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:47 INFO - 'LOGNAME': 'cltbld', 16:10:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:47 INFO - 'MOZ_NO_REMOTE': '1', 16:10:47 INFO - 'NO_EM_RESTART': '1', 16:10:47 INFO - 'PAGER': '/bin/cat', 16:10:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:47 INFO - 'PWD': '/builds/slave/test', 16:10:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:47 INFO - 'SHELL': '/bin/bash', 16:10:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObcnyCNFJF/Listeners', 16:10:47 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 16:10:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:47 INFO - 'USER': 'cltbld', 16:10:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:47 INFO - 'XPC_FLAGS': '0x0', 16:10:47 INFO - 'XPC_SERVICE_NAME': '0', 16:10:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:10:47 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-ymQ4N4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:10:47 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-VhRxaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:10:47 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-bfY_kw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:10:47 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-B9Mbi2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:10:48 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-kMCsKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:10:48 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-qFTd9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:10:48 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Snr4Nt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:10:48 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-dYeoAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:10:48 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-LVTtgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:10:48 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-kjBOUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:10:48 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-sGnWpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:10:48 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-1KFJ8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:10:48 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-gthBac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:10:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:10:48 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-W5qEPu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-HaBX95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:10:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-HjUs_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:10:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-vsdpZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:10:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:10:49 INFO - Running setup.py install for manifestparser 16:10:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:10:49 INFO - Running setup.py install for mozcrash 16:10:49 INFO - Running setup.py install for mozdebug 16:10:49 INFO - Running setup.py install for mozdevice 16:10:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:10:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:10:49 INFO - Running setup.py install for mozfile 16:10:50 INFO - Running setup.py install for mozhttpd 16:10:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:10:50 INFO - Running setup.py install for mozinfo 16:10:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:10:50 INFO - Running setup.py install for mozInstall 16:10:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:10:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:10:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:10:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:10:50 INFO - Running setup.py install for mozleak 16:10:50 INFO - Running setup.py install for mozlog 16:10:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:10:50 INFO - Running setup.py install for moznetwork 16:10:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:10:50 INFO - Running setup.py install for mozprocess 16:10:51 INFO - Running setup.py install for mozprofile 16:10:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:10:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:10:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:10:51 INFO - Running setup.py install for mozrunner 16:10:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:10:51 INFO - Running setup.py install for mozscreenshot 16:10:51 INFO - Running setup.py install for moztest 16:10:51 INFO - Running setup.py install for mozversion 16:10:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:10:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:10:51 INFO - Cleaning up... 16:10:51 INFO - Return code: 0 16:10:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:10: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')]} 16:10:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10: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')]} 16:10:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:51 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 0x1101f4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11016a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe1a48bbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110008d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110000b20>, '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.3afePF6PdJ/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.ObcnyCNFJF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 16:10:51 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 16:10:51 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 16:10:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3afePF6PdJ/Render', 16:10:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:51 INFO - 'HOME': '/Users/cltbld', 16:10:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:51 INFO - 'LOGNAME': 'cltbld', 16:10:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:51 INFO - 'MOZ_NO_REMOTE': '1', 16:10:51 INFO - 'NO_EM_RESTART': '1', 16:10:51 INFO - 'PAGER': '/bin/cat', 16:10:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:51 INFO - 'PWD': '/builds/slave/test', 16:10:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:51 INFO - 'SHELL': '/bin/bash', 16:10:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObcnyCNFJF/Listeners', 16:10:51 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 16:10:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:51 INFO - 'USER': 'cltbld', 16:10:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:51 INFO - 'XPC_FLAGS': '0x0', 16:10:51 INFO - 'XPC_SERVICE_NAME': '0', 16:10:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:10:52 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-fDQUjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:10:52 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)) 16:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:10:52 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-R1JKlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:10:52 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)) 16:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:10:52 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-c87kQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:10:52 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)) 16:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:10:52 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-IY8waM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:10:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 16:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:10:52 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-yGeJ2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:10:52 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)) 16:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:10:52 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-hWhM7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:10:52 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)) 16:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:10:52 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-F2QTJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:10:52 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)) 16:10:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:10:52 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Z_OfKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:10:53 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)) 16:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:10:53 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-YSCwsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:10:53 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)) 16:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:10:53 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-FWiNwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:10:53 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)) 16:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:10:53 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-H5Bp3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:10:53 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)) 16:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:10:53 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Y63gvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 16:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:10:53 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-mVfYl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:10:53 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)) 16:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:10:53 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-t3NtI4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:10:53 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)) 16:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:53 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-XPI78u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:53 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)) 16:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:10:53 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-qbS5Np-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 16:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:10:53 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-bJBgks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:10:53 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)) 16:10:53 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)) 16:10:53 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)) 16:10:53 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)) 16:10:53 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:10:53 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:10:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:10:53 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 16:10:53 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 16:10:53 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 16:10:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:56 INFO - Downloading blessings-1.6.tar.gz 16:10:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:10:56 INFO - Installing collected packages: blessings 16:10:56 INFO - Running setup.py install for blessings 16:10:56 INFO - Successfully installed blessings 16:10:56 INFO - Cleaning up... 16:10:56 INFO - Return code: 0 16:10:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:10:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:10:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:10:57 INFO - Reading from file tmpfile_stdout 16:10:57 INFO - Current package versions: 16:10:57 INFO - blessings == 1.6 16:10:57 INFO - blobuploader == 1.2.4 16:10:57 INFO - docopt == 0.6.1 16:10:57 INFO - functools32 == 3.2.3-2 16:10:57 INFO - jsonschema == 2.5.1 16:10:57 INFO - manifestparser == 1.1 16:10:57 INFO - mozInstall == 1.12 16:10:57 INFO - mozcrash == 1.0 16:10:57 INFO - mozdebug == 0.1 16:10:57 INFO - mozdevice == 0.48 16:10:57 INFO - mozfile == 1.2 16:10:57 INFO - mozhttpd == 0.7 16:10:57 INFO - mozinfo == 0.9 16:10:57 INFO - mozleak == 0.1 16:10:57 INFO - mozlog == 3.4 16:10:57 INFO - moznetwork == 0.27 16:10:57 INFO - mozprocess == 0.23 16:10:57 INFO - mozprofile == 0.28 16:10:57 INFO - mozrunner == 6.13 16:10:57 INFO - mozscreenshot == 0.1 16:10:57 INFO - mozsystemmonitor == 0.3 16:10:57 INFO - moztest == 0.7 16:10:57 INFO - mozversion == 1.4 16:10:57 INFO - psutil == 3.1.1 16:10:57 INFO - requests == 1.2.3 16:10:57 INFO - wsgiref == 0.1.2 16:10:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:10:57 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')]} 16:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:57 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')]} 16:10:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:57 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 0x1101f4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11016a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe1a48bbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110008d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110000b20>, '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.3afePF6PdJ/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.ObcnyCNFJF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 16:10:57 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 16:10:57 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 16:10:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3afePF6PdJ/Render', 16:10:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:57 INFO - 'HOME': '/Users/cltbld', 16:10:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:57 INFO - 'LOGNAME': 'cltbld', 16:10:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:57 INFO - 'MOZ_NO_REMOTE': '1', 16:10:57 INFO - 'NO_EM_RESTART': '1', 16:10:57 INFO - 'PAGER': '/bin/cat', 16:10:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:57 INFO - 'PWD': '/builds/slave/test', 16:10:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:57 INFO - 'SHELL': '/bin/bash', 16:10:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObcnyCNFJF/Listeners', 16:10:57 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 16:10:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:57 INFO - 'USER': 'cltbld', 16:10:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:57 INFO - 'XPC_FLAGS': '0x0', 16:10:57 INFO - 'XPC_SERVICE_NAME': '0', 16:10:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:57 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)) 16:10:57 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)) 16:10:57 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)) 16:10:57 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)) 16:10:57 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)) 16:10:57 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)) 16:10:57 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)) 16:10:57 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)) 16:10:57 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)) 16:10:57 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)) 16:10:57 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)) 16:10:57 INFO - Cleaning up... 16:10:57 INFO - Return code: 0 16:10:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:10:57 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')]} 16:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:57 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')]} 16:10:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1101f4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11016a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe1a48bbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110008d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110000b20>, '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.3afePF6PdJ/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.ObcnyCNFJF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 16:10:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:10:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:10:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3afePF6PdJ/Render', 16:10:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:58 INFO - 'HOME': '/Users/cltbld', 16:10:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:58 INFO - 'LOGNAME': 'cltbld', 16:10:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:58 INFO - 'MOZ_NO_REMOTE': '1', 16:10:58 INFO - 'NO_EM_RESTART': '1', 16:10:58 INFO - 'PAGER': '/bin/cat', 16:10:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:58 INFO - 'PWD': '/builds/slave/test', 16:10:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:58 INFO - 'SHELL': '/bin/bash', 16:10:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObcnyCNFJF/Listeners', 16:10:58 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 16:10:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:58 INFO - 'USER': 'cltbld', 16:10:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:58 INFO - 'XPC_FLAGS': '0x0', 16:10:58 INFO - 'XPC_SERVICE_NAME': '0', 16:10:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:10:58 INFO - Cleaning up... 16:10:58 INFO - Return code: 0 16:10:58 INFO - Running post-action listener: _resource_record_post_action 16:10:58 INFO - Running post-action listener: _start_resource_monitoring 16:10:58 INFO - Starting resource monitoring. 16:10:58 INFO - [mozharness: 2016-11-09 00:10:58.459661Z] Finished create-virtualenv step (success) 16:10:58 INFO - [mozharness: 2016-11-09 00:10:58.460705Z] Running install step. 16:10:58 INFO - Running pre-action listener: _resource_record_pre_action 16:10:58 INFO - Running main action method: install 16:10:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:10:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:10:58 INFO - Reading from file tmpfile_stdout 16:10:58 INFO - Detecting whether we're running mozinstall >=1.0... 16:10:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:10:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:10:58 INFO - Reading from file tmpfile_stdout 16:10:58 INFO - Output received: 16:10:58 INFO - Usage: mozinstall [options] installer 16:10:58 INFO - Options: 16:10:58 INFO - -h, --help show this help message and exit 16:10:58 INFO - -d DEST, --destination=DEST 16:10:58 INFO - Directory to install application into. [default: 16:10:58 INFO - "/builds/slave/test"] 16:10:58 INFO - --app=APP Application being installed. [default: firefox] 16:10:58 INFO - mkdir: /builds/slave/test/build/application 16:10:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:10:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:11:32 INFO - Reading from file tmpfile_stdout 16:11:32 INFO - Output received: 16:11:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:11:32 INFO - Running post-action listener: _resource_record_post_action 16:11:32 INFO - [mozharness: 2016-11-09 00:11:32.991760Z] Finished install step (success) 16:11:32 INFO - [mozharness: 2016-11-09 00:11:32.991920Z] Running run-tests step. 16:11:32 INFO - Running pre-action listener: _resource_record_pre_action 16:11:32 INFO - Running main action method: run_tests 16:11:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:11:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:11:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:11:33 INFO - 2016-11-08 16:11:33.006 screenresolution[1639:7441] starting screenresolution argv=screenresolution get 16:11:33 INFO - 2016-11-08 16:11:33.009 screenresolution[1639:7441] Display 0: 1600x1200x32@60 16:11:33 INFO - 2016-11-08 16:11:33.015 screenresolution[1640:7443] starting screenresolution argv=screenresolution list 16:11:33 INFO - Available Modes on Display 0 16:11:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:11:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:11:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:11:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:11:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:11:33 INFO - Intel Iris: 16:11:33 INFO - Chipset Model: Intel Iris 16:11:33 INFO - Type: GPU 16:11:33 INFO - Bus: Built-In 16:11:33 INFO - VRAM (Dynamic, Max): 1536 MB 16:11:33 INFO - Vendor: Intel (0x8086) 16:11:33 INFO - Device ID: 0x0a2e 16:11:33 INFO - Revision ID: 0x0009 16:11:33 INFO - Displays: 16:11:33 INFO - Display: 16:11:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:11:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:11:33 INFO - Display Serial Number: 1600x1200 60 16:11:33 INFO - Main Display: Yes 16:11:33 INFO - Mirror: Off 16:11:33 INFO - Online: Yes 16:11:33 INFO - Rotation: Supported 16:11:33 INFO - Return code: 0 16:11:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:11:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:11:33 INFO - Python 2.7.3 16:11:33 INFO - Return code: 0 16:11:33 INFO - grabbing minidump binary from tooltool 16:11:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:11:33 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 0x110008d30>, '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 0x10fe2edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110000b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:11:33 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 16:11:33 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 16:11:33 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 16:11:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:11:33 INFO - Return code: 0 16:11:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:11:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:11:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:11:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:11:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:11:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--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 16:11:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0005 --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 16:11:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3afePF6PdJ/Render', 16:11:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:11:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:11:33 INFO - 'HOME': '/Users/cltbld', 16:11:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:11:33 INFO - 'LOGNAME': 'cltbld', 16:11:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:11:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:11:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:33 INFO - 'MOZ_NO_REMOTE': '1', 16:11:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:11:33 INFO - 'NO_EM_RESTART': '1', 16:11:33 INFO - 'PAGER': '/bin/cat', 16:11:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:11:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:33 INFO - 'PWD': '/builds/slave/test', 16:11:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:11:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:11:33 INFO - 'SHELL': '/bin/bash', 16:11:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ObcnyCNFJF/Listeners', 16:11:33 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 16:11:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:11:33 INFO - 'USER': 'cltbld', 16:11:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:11:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:11:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:33 INFO - 'XPC_FLAGS': '0x0', 16:11:33 INFO - 'XPC_SERVICE_NAME': '0', 16:11:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:11:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--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 16:11:33 INFO - mozversion application_buildid: 20161108135923 16:11:33 INFO - mozversion application_changeset: 783356f1476eafd8e4d6fa5f3919cf6167e84f8d 16:11:33 INFO - mozversion application_display_name: Nightly 16:11:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:11:33 INFO - mozversion application_name: Firefox 16:11:33 INFO - mozversion application_remotingname: firefox 16:11:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:11:33 INFO - mozversion application_vendor: Mozilla 16:11:33 INFO - mozversion application_version: 52.0a1 16:11:33 INFO - mozversion platform_buildid: 20161108135923 16:11:33 INFO - mozversion platform_changeset: 783356f1476eafd8e4d6fa5f3919cf6167e84f8d 16:11:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:11:33 INFO - mozversion platform_version: 52.0a1 16:11:33 INFO - using testdate: 1478650293 16:11:33 INFO - actual date: 1478650293 16:11:33 INFO - starting webserver on 'localhost:49272' 16:11:33 INFO - SUITE-START | Running 2 tests 16:11:33 INFO - TEST-START | dromaeo_css 16:11:33 INFO - operating with platform_type : mac_ 16:11:33 INFO - Initialising browser for dromaeo_css test... 16:11:33 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpKQT4kv/profile http://localhost:49272/getInfo.html) 16:11:35 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 16:11:35 INFO - PROCESS | 1650 | colorDepth:24 16:11:35 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 16:11:35 INFO - PROCESS | 1650 | __metrics 16:11:36 INFO - PROCESS | 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:11:36 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:11:36 INFO - TEST-INFO | 1650: exit 0 16:11:36 INFO - Browser initialized. 16:11:36 INFO - Running cycle 1/1 for dromaeo_css test... 16:11:36 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpKQT4kv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:11:48 INFO - PROCESS | 1652 | RSS: Main: 239595520 16:11:48 INFO - PROCESS | 1652 | 16:12:39 INFO - PROCESS | 1652 | 1478650359659 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:14:53 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 16:14:54 INFO - PROCESS | 1652 | RSS: Main: 402210816 16:14:54 INFO - PROCESS | 1652 | 16:17:57 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 16:17:58 INFO - PROCESS | 1652 | RSS: Main: 371765248 16:17:58 INFO - PROCESS | 1652 | 16:21:00 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 16:21:01 INFO - PROCESS | 1652 | RSS: Main: 349151232 16:21:01 INFO - PROCESS | 1652 | 16:24:03 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 16:24:04 INFO - PROCESS | 1652 | RSS: Main: 337592320 16:24:04 INFO - PROCESS | 1652 | 16:27:07 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 16:27:08 INFO - PROCESS | 1652 | RSS: Main: 331333632 16:27:08 INFO - PROCESS | 1652 | 16:30:11 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 16:30:11 INFO - PROCESS | 1652 | RSS: Main: 394321920 16:30:11 INFO - PROCESS | 1652 | 16:30:11 INFO - PROCESS | 1652 | __start_tp_report 16:30:11 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 16:30:11 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 16:30:11 INFO - PROCESS | 1652 | |i|pagename|runs| 16:30:11 INFO - PROCESS | 1652 | |0;dojo.html;3301.9504707429387;3340.89977300681;3348.6651334866515;3375.223698549332;3396.9150771230716;5638.407967163445;5657;5696.601237913343;5722.856928576788;5797.507211886984;5711.485966263035;5781.710914454271;5892.764289428418;5906.5274778017765;5918.822435326936;5127.769250383723;5132.101882170624;5162.974185129088;5214.895702085956;5252.52727254547;5348.786048558053;5350.464953504638;5356.4107948125675;5362.088444964365;5376.677399356032;5129.692218466904;5296.761645725913;5321.467853214686;5374.6506477078865;5388.488093631099;5194.324737784102;5209.088409528317;5219.217117432378;5257.947420525784;5262.8684282892855;5248.317718696583;5250.238715386247;5261.6842989420375;5286.629935904488;5311.654742441729;5780.942190578083;5786.1610066540115;5797.449242321973;5802.274715660543;5820.912686309709;6084.847878803021;6126.253663788776;6128.319797384577;6237.844053898643;6242.157308763304;6129.49238426781;6150.354212807635;6185.041318595625;6222.813315600494;6233.812985610439;5764.394738552475;5765.567582431335;5793.449622285834;5800.187973683687;5831.212786273799;5409.864753381118;6270.9372906271265;6285.057241413824;6313.715882785285;6317.684115794192;6166.979614401969;6176.4441200292185;6190.7523699051535;6193.628382297076;6193.907091393633;6120.203342909657;6177.81466556004;6201.751929922752;6234;6318.462930650854;5191.5846732261325;6165.075238714228;6269.937300627026;6278.121063051177;6278.434940855345;6289;6291.182146320949;6296.370362963758;6299.464545513591;6309.14826498426;4850.393700787402;4921.237208232729;4978.178600530872;4984.850454486371;5011.599072074226;4368.650507959355;4426.380306757057;4428.424304840414;4432.3351497275025;4465.307877279027;5785.739641716133;5798.478136967608;5799.623024503394;5800.767969281266;5813.040848260074;6324.33594472583;6329.873402531924;6330.208723909512;6332.746690132435;6338.334474880164;5022.694099534147;5586.748596313176;5597.328320601554;5735.913961290584;5788.044972579477;5463.180522921514;5467.371252305956;5468.617196796186;5473.917891231635;5479.0959491683425;6393.968030159819;6403.103565500926;6419.614823110535;6428.19647544057;6430.421262086435;6109.95128740435;6229.750809967551;6239.875202496016;6331.556791024768;6337.397947195009;6298.1812364392335;6320.209973753281;6332.556721029484;6402.903956440564;6414.486841052611;6221.751129954932;6263.3736626337;6283.622982621057;6286.717097730704;6287.559870808999;6104.610739188311;6301.842453938688;6323.841903952254;6353.301136875032;6374.458171055512;5057.3931128263375;6057.636541807506;6101.368489499298;6102.420270074336;6188.4739797117745;5097.209932460474;6111.96944015277;6149.938500614937;6171.814845554641;6192.225971753531;6002.429769171922;6034.7284372202475;6058.787942421994;6061.515078793598;6067.635941843503;5994.10088486713;6072.635641861502;6097.451229389376;6108.205933228652;6137.35557766446;6008.849778755566;6017.3681763414215;6035.577509574287;6045;6046.697665116639 16:30:11 INFO - PROCESS | 1652 | |1;ext.html;500.1572350589257;505.17404044387416;515.9871003224916;516.4628786062495;516.7390467813755;28749.712502874965;29104.563431548544;30272.09183724486;33408.49887251693;37442.81278593603;38842.80578597103;39197.60802391982;39242.018949526275;39256.80371598138;39274.912388579745;8970.10298970101;9121.133492318218;9134.406263592879;9136.634534618608;9139.268858491305;9089.772755681095;9188.402753821014;9193.862092068624;9197.126273004054;9204.263658907304;9162.267018638493;9240.630374785;9242.584083716249;9249.676261330855;9250.814983700355;9101.544922753868;9169.908300916972;9176.220021298197;9182.72451826443;9194.954025229898;9049.547522623909;9097.363184577081;9106.31702622306;9108.271338292921;9123.315751318678;36623.08442288964;36968.59704119224;37004.62995370039;37051;37071.07322316937;3292.37444885471;3297.7856439331367;3419.264858055505;3483.5819701635965;3512.982435087834;27504.259618813558;28653.856730716212;28871.845126194927;28950.710492895014;28981.13056608305;2697.6223328733995;3065.126438964907;3177.158053115926;3186.522021696764;3284.064041748114;374239;459516.70241648913;462289;466114;466875;52436.54289385445;52672.473275267526;52723;52748;52776;55415.72292138513;55463.44536554583;55563.72218138964;55578;55640;15216.923915380352;15412.075275483507;15429;15437.614059648598;15543.84456155424;10180.643677471253;12148.088893333035;12186.390680466118;12199.634010979686;12217.083718721131;14013.299335033207;14424.783628245585;14447.638809029859;14580.927095364455;14664;5518.806841760599;6468.256150542654;6617;6619.933800661932;6634.701438435282;874.5627186406797;921.3412410126791;954.398728800851;978.7650963768787;1006.8892421833592;27425.86287068552;28980.420391592474;29189.27026824304;30332.84833575818;30532.694673052985;1031.9277650564538;1054.6007832181847;1085.7513859062142;1089.1123734156638;1117.8267368557886;13353.465861365437;13659.795103073462;14236.06256117279;14518.564443066725;14522;530495;540180;572475;576552;578854;49088.77278068077;49288;49295;49314;49335.50664493381;1325.516186591887;1347.892168626527;1373.2790285100402;1374.1892283552752;1375.931203439844;22449.438764030376;27029.459410812065;27246.910123595626;27760;27828.582571261853;45330;45441.318380224984;45600.7719961398;45719.77140114278;45806.54193458023;520102;521275;521812;521860;523168;23632.881835590713;23766.405839854142;24194.75805241925;24209.39476513031;25169.119080832083;42623.7212883614;42833.143337133704;43726.34410483909;43738;44067;35179;35782.64217357793;35793.82103089468;36659.81670091632;37045;35346.64653353434;35563.288734224654;36335.454968176025;36522.72170474021;36714.63285367112;35091.64908350986;35314.11714707153;35574.466383004794;35802.92591222163;35994.64005359913;30536.236594085327;31769.20576985594;31855.903055511008;31863.522047169776;31975.36049278955;19380.709289360388;23654.88172559126;24102.51794964126;24166.39584010414;24369.26892193237 16:30:11 INFO - PROCESS | 1652 | |2;jquery.html;350.74921431176705;367.602988772126;369.0975564744202;373.3186933845731;395.64984988285374;14451.783223251647;14962.251887405628;15558.299876505556;19719.21123155076;20163.59672806543;18874.43376698698;19412.9029354853;20000.29998950037;20160.798392016077;20300;5545.306836645419;5833.445822646863;5837.854053648651;5848.035074212749;5855.79504717335;4632.073585282957;5777.422257774231;5816.767329306823;5834.154047663086;5849.590528662995;5422.8915421691545;5578.832635020955;5788.797392091277;5803.854903627422;5814.883702325951;5609.1305847593685;5721.370649228582;5724.313082430135;5727.627704199213;5731.312242530881;4777.020710754322;5000.774965126578;5014.899702005976;5028;5030.069437154101;24946.750532494625;25111.748882511307;25422.237332880042;25430;25653.48693026129;2223.1996481266733;2372.466195106105;2900.6084178635824;2959.881604735808;3162.794418362822;12026.759464810655;15076.705872995344;15447.613809654737;15450.9227453862;15986.520404387888;1618.304129224433;1837.549800298922;1860.1443336065427;1861.2301864220942;1919.3666090190343;110254.44872775665;112569.43715281453;115588;116198;116329.83670163275;10185.74535636615;10410.531526081344;10481.842772358421;10704.678859634223;11053.336799792038;13421.731565368638;13466;13484;13491.865081349257;13517.729645407037;7348.889766653505;8101.756947291591;8144.633491492778;8396.79008024792;8533.871991920127;8156.673733050611;8470.280026197719;8477.788055298666;8484.27883629886;8685.305175585938;5440.591955603346;6337.714802833883;6472.385123413268;6480.995445705923;6493.448056915121;1759.9120043997748;1808.52074200337;1812.7915289741584;1817.972730409045;1819.4450692538903;856.1004623938022;873.991260087391;917.1149840404042;964.9276304277207;989.1443901025705;20624;21253.89373053125;21263.68104478434;21330.360089197347;21356.786432135792;763.9388848892075;938.4932136646271;971.6696323250129;981.8183636027358;990.5096976996433;10949.781004379867;11425.60010399632;11466.94266528662;11609.3614851184;11633.825492617618;544097;545165;553581;566738;567185;110428;111970.44014779874;113206;113230.66535601112;116209.8379016199;971.9416834989923;978.8678528398575;1009.2682804966487;1009.9697009089739;1010.3937637417491;19010.714839277698;19203.90398048001;19273.5181620455;19317.8068219316;19400.932948687958;49295;50257.74871125621;52698.47301527089;53201;54161.458385415644;71394.28605714084;73712;75155.248447517;75594.11014724676;76071.61964190143;7366.5635201135265;7574.697012119454;7872.488288261245;7920.485168464032;8006.079300880473;11437.256578322382;11513.88486115128;12818.61544153677;12850.100492965403;13206.933965330496;10986.890131098906;11023.503942322775;11070.446477675923;11077.411180173503;11131.610393636183;10923.617673381394;11126.388048657078;11161.995420412202;11254.83117753218;11316.83024754613;10931.726706832393;11188.720281993015;11230.66308010761;11460.541578337106;11504.424778760862;6132.8773424532155;7472.40220782347;7653.6555854985545;7853.764387068397;8145.918540814516;9533.761655958657;9855.852162217428;10046;10707.625233116803;10919.344839309666 16:30:11 INFO - PROCESS | 1652 | |3;mootools.html;1319.7756381415159;1470.536780914012;1504.8645621894032;1668.416054380967;1851.1484717030166;7462.776116716501;7874.4094192935545;8006.239407256316;8346.83306333873;8366.28886544643;8239.91760082399;8323.33413326934;8330.37522185837;8333.250007499324;8339.416240863142;2284.7829456201634;2314.884255787212;2316.1661801751357;2318.6522021696796;2319.663648770927;2284.189112864934;2332.2769941318247;2347.471818840764;2350.388898886293;2353.776391242829;2274.9658755118685;2287.3252390544744;2306.5732839424677;2309.4457330240707;2310.3069079276233;2255.4474153832252;2266.8866556672183;2288.8283378746496;2291.724993000834;2301.413139649387;2283.3264187064856;2304.2511183865313;2306.489249541553;2310.688057112285;2312.5721741477882;7985.401094917905;8269.29710974568;8350.248477637042;8353.95823020881;8359.331253499766;5027.899442011139;5234.293370394986;5238.423773384925;5243.86890327741;5258.789648414059;2873.5115030444877;3431.0736101252523;3535.8762443314617;3618.8190590470635;3622.6920711739535;1062.659948816379;1094.0153861524611;1701.2004357951744;1737.4179649817327;1826.4520643806804;10423.322484038437;10852.077573406192;10936.398498082603;10953.233273670847;10959.890401095967;3726.925461490755;4276.422682937764;4426.380306757057;4443.4445694288215;4457.398251236042;8655.783605409915;8690.695825646071;8695.739127826175;8703.695370661997;8707.303415726727;5025.120603894304;5041.294218809369;5042.193249080129;5049.596032317406;5056.544910958031;4059.5331536873045;4186.727862688916;4727.669063165546;4755.548222918817;4759.666823322404;3332.9333413331597;3517.648235176462;3555.6266592007983;3644.9817750911075;3652.7625704329134;1036.657902892044;1130.4912789244872;1134.6936327191613;1135.3244819332483;1141.5034460009756;795.1849354411659;886.1581497577328;893.9374243803002;899.4153800029902;923.1645360948219;5282.124845679728;5436.134659479743;5632.549396048388;5728.1407788831175;5811.0121279381765;801.531104303977;810.3962547901851;897.3045889435609;960.3037797596686;1013.6756238003768;3660.5790334111384;3776.4712940188397;3793.165503589206;3862.304785138702;3925.4896863407575;9697.418154910727;9988.101070903653;10138.290319677699;10302.278840481244;10323.587056517656;9824.312298139062;10269.948650256702;10277.845832312521;10282.897171028193;10296.279260451696;7731.613419329011;7855.800375894759;7874.645640946172;7887;7996.0804507481225;4839.806407743651;5537.889242215214;5571.4428557143965;5580.9162862557905;5664.971675141598;8374.623141958504;8806.339524535557;9299.814003719752;9517.857232141661;9631.518424078908;9565.33042687033;9635.325527213308;9645.180159686504;9668;9747.610095596097;6256.249250090018;6444.291127976013;6460.030995350735;6698.095757072733;6851;7711.575863327571;7904.446688731734;7974;8072.273495385443;8078.7172448964;6061.272647282354;7003.859922801617;7226.313500217471;7256.528325658815;7322.524035937647;5784.797532086357;6997.825054373681;7027.4378049756915;7067.399271061811;7099.467539934422;6589.604623722592;6683.298253683488;6765.661716914036;6784.3215678433735;6784.626845523544;4818.759062046814;4882.975585122052;4896.436909755424;4926.113299606105;4990.101781679333;4870.464248932543;4883.340748998839;4921.483244259444;4981.252812078217;4986.202207646759 16:30:11 INFO - PROCESS | 1652 | |4;prototype.html;293.84573099122963;295.19706398595827;303.3553698390919;308.80699562773265;309.979851309665;4639.6984196027315;4696.859094227174;4993.675411098276;5114.232865070241;5125.564841844281;4516.232240519112;4588.472325682547;4788.749287834712;4891.755412229392;4964.304997300381;3223.7904536205187;3580.946285805715;3926.607339266079;3967.3255546557157;3973.46358241638;3296.8516416761304;3555.715542756573;3631.855965370913;3943.4508941952186;3948.447217389568;3364.764466487347;3691.630836916314;3696.2792255510185;3934.586868378822;3938.23204475126;3688.981555092207;3732.384156614142;3902.902427439308;3916.432117342998;3971.9801400992956;3433.5193072969805;3436.8453419596176;3451.6030656474577;3461.2039230977016;3467.6532346765375;4355.7822108894425;5028.647994640377;5036.597072234249;5046.7224302663335;5058.342415485963;3872.4965754452023;4207.768572728499;4339.392485052095;4458.598726114666;4466.665000125004;4255.085156691295;4952.83608352037;5087.389513258396;5317.069512835238;5367.162478827657;3140.9685903140903;3228.0961330827404;3799.525059367579;3807.638274363931;3820.1977584707247;4539.001419687664;5528.004959107359;5572.275604171432;5670.206171136044;5677.460641239076;3138.2468207630004;3701.4447832824753;3751.9624803751612;3759.661630453272;3770.490983717218;3206.0381885434276;3610.891673249807;3796.4115562087923;3802.1825307559006;3814.6948244140403;3927.7446965947693;4138.337865941435;4246.660267178619;4410.8090815479645;4438.667099967515;4648.651351148677;5020.974895125574;5048.949510504848;5394.514493695587;5429.226687452262;1073.9087177589836;1217.7442737025146;1225.6997035726126;1229.932353720554;1237.3751255615857;770.3990887108065;779.4855395438983;798.22572105058;801.4750338528273;831.6631764135535;5059.620528460307;5113.335266415343;5147.996140752517;5158.045761534128;5183.9113786105345;4967.90064198714;5572.275604171432;5580.525655319262;5587.469190426903;5592.133219351007;4709.139889743532;4931.630127740362;4949.752512374367;5130.948690513122;5145.742712864342;2734.4394399148127;2763.701060501561;2778.8054836161677;2788.2889863084783;2790.7906906982057;4942.654014219042;5325.328525120812;5421.593380496479;5428.83713488596;5477;4407.988799453789;4520.412346394947;4615.538446155358;5396.676199428047;5419.34967803866;4804.855854324376;4956.884700942259;4971.81240254287;5040.7902103495635;5053.823116190915;4760.714357138444;5242.58059355243;5249.375312343828;5275.8681032972945;5292.973535132453;4113.424120623175;4525.886852828706;4688.437387513521;4825.734584597881;5556.833295001156;4862.5380588844;5071.315372424676;5697.173909782975;5798.17357532376;5853.9121913172185;3293.802371857696;3796.240751849586;3809.9428508572946;3812.6949844011856;3819.980900095482;4588.839390621312;5061.584105007753;5275.971185618768;5609.242752228397;5668.979583674978;5425.077736784835;5453.372862120907;5456.918146227889;5466.3986961435;5480.205370221375;4798.448178459522;5455.9633669602645;5470.316210473691;5493.593971737549;5494.890102198014;4405.581469760367;4842.813510689763;4956.578690811177;5331.587129410632;5346.946530534645;4637.704336353527;5126.79492820283;5129.487051294841;5321.275107424741;5330.600204984719;4804.327394164749;5246.868828279324;5300.9734951325;5303;5317.441668624893 16:30:11 INFO - PROCESS | 1652 | |5;yui.html;605.2810019544494;670.6828501053504;677.1366507702676;703.4899697719154;805.0741647105827;14033.92983035086;14677.192754398517;14923.776143357858;14983.325750341233;15147;13313.068085234037;13369.732605347912;13539.052266341336;13793.172409655428;13807.447702091904;796.74902405742;804.951702897825;812.4556547113418;813.5769399912042;815.902091748991;666.0874601795523;685.7291369908908;688.30481213974;815.3436483630675;819.0008190008181;843.7763992541982;867.1848462445313;871.2724874729577;873.3406278259905;873.8427083125035;857.2284943550854;859.1923591823665;859.5229647545609;860.3332417376521;860.6514361683528;505.6182582150453;635.8219698484431;636.522608043967;636.6402982314977;637.2066776862789;11795.410229488578;12982.09125361225;13412.597622071353;13432.261225632587;13797.586072417844;13273.734525309535;13495.932520337434;13586.524471643444;13713.520026799015;13790.103643263255;11266.211365204439;11310.773784524263;11333.206675532716;11347.886521134766;11707.356095414749;3589.228315912065;3594.730395220369;3616.168281295317;3640.672339489459;3758.8120593970466;71150.28849711489;87527.12472875253;88144;89417;91498.99999999933;10355.585776569003;10461.22465145756;10703.25077244601;10748.838767418494;10774.353538787698;19748.40754777359;20140;20277.29029483961;20285.391438256876;20294.18823247083;7392.963035184897;7508.62456877154;7619.771406857803;7714.074311082706;7794.181610930884;3609.9819500902327;4269.466316710411;4293.935590966138;4372.781360931941;4487.012857171417;3315.3700796849002;3397.3884700753606;3411.9829400852836;3433.2446861690387;3433.261848702491;577.9508741756998;593.4866340615337;628.7704987679464;633.4678869749432;638.9361063893573;566.8016194331951;617.6541136963315;630.854903372227;660.089077073645;664.166471078793;8533.146685331541;8754.606042727964;8893.911060889437;9071;9076.455412675261;582.6329412470202;616.4236438929615;626.9686515674198;650.8047585724264;651.1014799576554;4158.771267580251;4256.593527262541;4391.231534481453;4400.1199760047475;4511.458624965026;75610.62194689101;77350.61324693453;104253.47873260586;118391;119629;67409.66295168492;81373.59313203515;103213;104334.47832760788;109572.4521377388;59316.11025834616;83570.74643880347;90980;91822;92068.53965730128;7112.253213412517;8232.62953167097;8556.957215213884;8702.390832641655;8720.607572659372;37199.490526986454;41570.12934418019;51397.22904156515;53778.462215377345;54020;49360.75319623379;49838.75403114951;68582.65708671424;74279;76812.61593691996;11370.488328025092;11471.13966452536;11491.54033838637;11549.249298795552;11815.82276265874;11352.886471135183;11838.171328006956;11863.110266729856;11920.284782913053;11982.58060967862;11414.714632134263;11415.086793056702;11579.88420115788;11749.530018799152;11994.700132496757;11362.03422709079;11452.427378631202;11726.765464690829;11956.163068585116;12023.398830058288;11464.082873370277;11478.311301321948;11506.252093613888;11563.190576659554;11693.298402095561;7392.815179620553;9536.189423898768;9749.122578967927;9792.265580081379;9849.60601575929;9820.754481137743;10002.749931251778;10096.141827944413;10106.545205465918;10220.386776793417 16:30:11 INFO - PROCESS | 1652 | __end_tp_report 16:30:11 INFO - PROCESS | 1652 | __start_cc_report 16:30:11 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1836 16:30:11 INFO - PROCESS | 1652 | __end_cc_report 16:30:11 INFO - PROCESS | 1652 | __startTimestamp1478651411902__endTimestamp 16:30:11 INFO - PROCESS | 1652 | 16:30:11 INFO - PROCESS | 1652 | ------- Summary: start ------- 16:30:11 INFO - PROCESS | 1652 | Number of tests: 6 16:30:11 INFO - PROCESS | 1652 | 16:30:11 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5761.35 Median:6017.37 stddev:649.43 (10.8%) stddev-sans-first:622.10 16:30:11 INFO - PROCESS | 1652 | Values: 3302.0 3340.9 3348.7 3375.2 3396.9 5638.4 5657.0 5696.6 5722.9 5797.5 5711.5 5781.7 5892.8 5906.5 5918.8 5127.8 5132.1 5163.0 5214.9 5252.5 5348.8 5350.5 5356.4 5362.1 5376.7 5129.7 5296.8 5321.5 5374.7 5388.5 5194.3 5209.1 5219.2 5257.9 5262.9 5248.3 5250.2 5261.7 5286.6 5311.7 5780.9 5786.2 5797.4 5802.3 5820.9 6084.8 6126.3 6128.3 6237.8 6242.2 6129.5 6150.4 6185.0 6222.8 6233.8 5764.4 5765.6 5793.4 5800.2 5831.2 5409.9 6270.9 6285.1 6313.7 6317.7 6167.0 6176.4 6190.8 6193.6 6193.9 6120.2 6177.8 6201.8 6234.0 6318.5 5191.6 6165.1 6269.9 6278.1 6278.4 6289.0 6291.2 6296.4 6299.5 6309.1 4850.4 4921.2 4978.2 4984.9 5011.6 4368.7 4426.4 4428.4 4432.3 4465.3 5785.7 5798.5 5799.6 5800.8 5813.0 6324.3 6329.9 6330.2 6332.7 6338.3 5022.7 5586.7 5597.3 5735.9 5788.0 5463.2 5467.4 5468.6 5473.9 5479.1 6394.0 6403.1 6419.6 6428.2 6430.4 6110.0 6229.8 6239.9 6331.6 6337.4 6298.2 6320.2 6332.6 6402.9 6414.5 6221.8 6263.4 6283.6 6286.7 6287.6 6104.6 6301.8 6323.8 6353.3 6374.5 5057.4 6057.6 6101.4 6102.4 6188.5 5097.2 6112.0 6149.9 6171.8 6192.2 6002.4 6034.7 6058.8 6061.5 6067.6 5994.1 6072.6 6097.5 6108.2 6137.4 6008.8 6017.4 6035.6 6045.0 6046.7 16:30:11 INFO - PROCESS | 1652 | 16:30:11 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:63075.62 Median:36986.61 stddev:136640.97 (369.4%) stddev-sans-first:136943.51 16:30:11 INFO - PROCESS | 1652 | Values: 500.2 505.2 516.0 516.5 516.7 28749.7 29104.6 30272.1 33408.5 37442.8 38842.8 39197.6 39242.0 39256.8 39274.9 8970.1 9121.1 9134.4 9136.6 9139.3 9089.8 9188.4 9193.9 9197.1 9204.3 9162.3 9240.6 9242.6 9249.7 9250.8 9101.5 9169.9 9176.2 9182.7 9195.0 9049.5 9097.4 9106.3 9108.3 9123.3 36623.1 36968.6 37004.6 37051.0 37071.1 3292.4 3297.8 3419.3 3483.6 3513.0 27504.3 28653.9 28871.8 28950.7 28981.1 2697.6 3065.1 3177.2 3186.5 3284.1 374239.0 459516.7 462289.0 466114.0 466875.0 52436.5 52672.5 52723.0 52748.0 52776.0 55415.7 55463.4 55563.7 55578.0 55640.0 15216.9 15412.1 15429.0 15437.6 15543.8 10180.6 12148.1 12186.4 12199.6 12217.1 14013.3 14424.8 14447.6 14580.9 14664.0 5518.8 6468.3 6617.0 6619.9 6634.7 874.6 921.3 954.4 978.8 1006.9 27425.9 28980.4 29189.3 30332.8 30532.7 1031.9 1054.6 1085.8 1089.1 1117.8 13353.5 13659.8 14236.1 14518.6 14522.0 530495.0 540180.0 572475.0 576552.0 578854.0 49088.8 49288.0 49295.0 49314.0 49335.5 1325.5 1347.9 1373.3 1374.2 1375.9 22449.4 27029.5 27246.9 27760.0 27828.6 45330.0 45441.3 45600.8 45719.8 45806.5 520102.0 521275.0 521812.0 521860.0 523168.0 23632.9 23766.4 24194.8 24209.4 25169.1 42623.7 42833.1 43726.3 43738.0 44067.0 35179.0 35782.6 35793.8 36659.8 37045.0 35346.6 35563.3 36335.5 36522.7 36714.6 35091.6 35314.1 35574.5 35802.9 35994.6 30536.2 31769.2 31855.9 31863.5 31975.4 19380.7 23654.9 24102.5 24166.4 24369.3 16:30:11 INFO - PROCESS | 1652 | 16:30:11 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:33136.18 Median:25426.12 stddev:92592.73 (364.2%) stddev-sans-first:92819.75 16:30:11 INFO - PROCESS | 1652 | Values: 350.7 367.6 369.1 373.3 395.6 14451.8 14962.3 15558.3 19719.2 20163.6 18874.4 19412.9 20000.3 20160.8 20300.0 5545.3 5833.4 5837.9 5848.0 5855.8 4632.1 5777.4 5816.8 5834.2 5849.6 5422.9 5578.8 5788.8 5803.9 5814.9 5609.1 5721.4 5724.3 5727.6 5731.3 4777.0 5000.8 5014.9 5028.0 5030.1 24946.8 25111.7 25422.2 25430.0 25653.5 2223.2 2372.5 2900.6 2959.9 3162.8 12026.8 15076.7 15447.6 15450.9 15986.5 1618.3 1837.5 1860.1 1861.2 1919.4 110254.4 112569.4 115588.0 116198.0 116329.8 10185.7 10410.5 10481.8 10704.7 11053.3 13421.7 13466.0 13484.0 13491.9 13517.7 7348.9 8101.8 8144.6 8396.8 8533.9 8156.7 8470.3 8477.8 8484.3 8685.3 5440.6 6337.7 6472.4 6481.0 6493.4 1759.9 1808.5 1812.8 1818.0 1819.4 856.1 874.0 917.1 964.9 989.1 20624.0 21253.9 21263.7 21330.4 21356.8 763.9 938.5 971.7 981.8 990.5 10949.8 11425.6 11466.9 11609.4 11633.8 544097.0 545165.0 553581.0 566738.0 567185.0 110428.0 111970.4 113206.0 113230.7 116209.8 971.9 978.9 1009.3 1010.0 1010.4 19010.7 19203.9 19273.5 19317.8 19400.9 49295.0 50257.7 52698.5 53201.0 54161.5 71394.3 73712.0 75155.2 75594.1 76071.6 7366.6 7574.7 7872.5 7920.5 8006.1 11437.3 11513.9 12818.6 12850.1 13206.9 10986.9 11023.5 11070.4 11077.4 11131.6 10923.6 11126.4 11162.0 11254.8 11316.8 10931.7 11188.7 11230.7 11460.5 11504.4 6132.9 7472.4 7653.7 7853.8 8145.9 9533.8 9855.9 10046.0 10707.6 10919.3 16:30:11 INFO - PROCESS | 1652 | 16:30:11 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5391.53 Median:5034.60 stddev:2952.44 (58.6%) stddev-sans-first:2944.86 16:30:11 INFO - PROCESS | 1652 | Values: 1319.8 1470.5 1504.9 1668.4 1851.1 7462.8 7874.4 8006.2 8346.8 8366.3 8239.9 8323.3 8330.4 8333.3 8339.4 2284.8 2314.9 2316.2 2318.7 2319.7 2284.2 2332.3 2347.5 2350.4 2353.8 2275.0 2287.3 2306.6 2309.4 2310.3 2255.4 2266.9 2288.8 2291.7 2301.4 2283.3 2304.3 2306.5 2310.7 2312.6 7985.4 8269.3 8350.2 8354.0 8359.3 5027.9 5234.3 5238.4 5243.9 5258.8 2873.5 3431.1 3535.9 3618.8 3622.7 1062.7 1094.0 1701.2 1737.4 1826.5 10423.3 10852.1 10936.4 10953.2 10959.9 3726.9 4276.4 4426.4 4443.4 4457.4 8655.8 8690.7 8695.7 8703.7 8707.3 5025.1 5041.3 5042.2 5049.6 5056.5 4059.5 4186.7 4727.7 4755.5 4759.7 3332.9 3517.6 3555.6 3645.0 3652.8 1036.7 1130.5 1134.7 1135.3 1141.5 795.2 886.2 893.9 899.4 923.2 5282.1 5436.1 5632.5 5728.1 5811.0 801.5 810.4 897.3 960.3 1013.7 3660.6 3776.5 3793.2 3862.3 3925.5 9697.4 9988.1 10138.3 10302.3 10323.6 9824.3 10269.9 10277.8 10282.9 10296.3 7731.6 7855.8 7874.6 7887.0 7996.1 4839.8 5537.9 5571.4 5580.9 5665.0 8374.6 8806.3 9299.8 9517.9 9631.5 9565.3 9635.3 9645.2 9668.0 9747.6 6256.2 6444.3 6460.0 6698.1 6851.0 7711.6 7904.4 7974.0 8072.3 8078.7 6061.3 7003.9 7226.3 7256.5 7322.5 5784.8 6997.8 7027.4 7067.4 7099.5 6589.6 6683.3 6765.7 6784.3 6784.6 4818.8 4883.0 4896.4 4926.1 4990.1 4870.5 4883.3 4921.5 4981.3 4986.2 16:30:11 INFO - PROCESS | 1652 | 16:30:11 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4278.02 Median:4801.39 stddev:1309.57 (27.3%) stddev-sans-first:1278.64 16:30:11 INFO - PROCESS | 1652 | Values: 293.8 295.2 303.4 308.8 310.0 4639.7 4696.9 4993.7 5114.2 5125.6 4516.2 4588.5 4788.7 4891.8 4964.3 3223.8 3580.9 3926.6 3967.3 3973.5 3296.9 3555.7 3631.9 3943.5 3948.4 3364.8 3691.6 3696.3 3934.6 3938.2 3689.0 3732.4 3902.9 3916.4 3972.0 3433.5 3436.8 3451.6 3461.2 3467.7 4355.8 5028.6 5036.6 5046.7 5058.3 3872.5 4207.8 4339.4 4458.6 4466.7 4255.1 4952.8 5087.4 5317.1 5367.2 3141.0 3228.1 3799.5 3807.6 3820.2 4539.0 5528.0 5572.3 5670.2 5677.5 3138.2 3701.4 3752.0 3759.7 3770.5 3206.0 3610.9 3796.4 3802.2 3814.7 3927.7 4138.3 4246.7 4410.8 4438.7 4648.7 5021.0 5048.9 5394.5 5429.2 1073.9 1217.7 1225.7 1229.9 1237.4 770.4 779.5 798.2 801.5 831.7 5059.6 5113.3 5148.0 5158.0 5183.9 4967.9 5572.3 5580.5 5587.5 5592.1 4709.1 4931.6 4949.8 5130.9 5145.7 2734.4 2763.7 2778.8 2788.3 2790.8 4942.7 5325.3 5421.6 5428.8 5477.0 4408.0 4520.4 4615.5 5396.7 5419.3 4804.9 4956.9 4971.8 5040.8 5053.8 4760.7 5242.6 5249.4 5275.9 5293.0 4113.4 4525.9 4688.4 4825.7 5556.8 4862.5 5071.3 5697.2 5798.2 5853.9 3293.8 3796.2 3809.9 3812.7 3820.0 4588.8 5061.6 5276.0 5609.2 5669.0 5425.1 5453.4 5456.9 5466.4 5480.2 4798.4 5456.0 5470.3 5493.6 5494.9 4405.6 4842.8 4956.6 5331.6 5346.9 4637.7 5126.8 5129.5 5321.3 5330.6 4804.3 5246.9 5301.0 5303.0 5317.4 16:30:11 INFO - PROCESS | 1652 | 16:30:11 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:19280.66 Median:4499.24 stddev:28614.35 (636.0%) stddev-sans-first:28660.26 16:30:11 INFO - PROCESS | 1652 | Values: 605.3 670.7 677.1 703.5 805.1 14033.9 14677.2 14923.8 14983.3 15147.0 13313.1 13369.7 13539.1 13793.2 13807.4 796.7 805.0 812.5 813.6 815.9 666.1 685.7 688.3 815.3 819.0 843.8 867.2 871.3 873.3 873.8 857.2 859.2 859.5 860.3 860.7 505.6 635.8 636.5 636.6 637.2 11795.4 12982.1 13412.6 13432.3 13797.6 13273.7 13495.9 13586.5 13713.5 13790.1 11266.2 11310.8 11333.2 11347.9 11707.4 3589.2 3594.7 3616.2 3640.7 3758.8 71150.3 87527.1 88144.0 89417.0 91499.0 10355.6 10461.2 10703.3 10748.8 10774.4 19748.4 20140.0 20277.3 20285.4 20294.2 7393.0 7508.6 7619.8 7714.1 7794.2 3610.0 4269.5 4293.9 4372.8 4487.0 3315.4 3397.4 3412.0 3433.2 3433.3 578.0 593.5 628.8 633.5 638.9 566.8 617.7 630.9 660.1 664.2 8533.1 8754.6 8893.9 9071.0 9076.5 582.6 616.4 627.0 650.8 651.1 4158.8 4256.6 4391.2 4400.1 4511.5 75610.6 77350.6 104253.5 118391.0 119629.0 67409.7 81373.6 103213.0 104334.5 109572.5 59316.1 83570.7 90980.0 91822.0 92068.5 7112.3 8232.6 8557.0 8702.4 8720.6 37199.5 41570.1 51397.2 53778.5 54020.0 49360.8 49838.8 68582.7 74279.0 76812.6 11370.5 11471.1 11491.5 11549.2 11815.8 11352.9 11838.2 11863.1 11920.3 11982.6 11414.7 11415.1 11579.9 11749.5 11994.7 11362.0 11452.4 11726.8 11956.2 12023.4 11464.1 11478.3 11506.3 11563.2 11693.3 7392.8 9536.2 9749.1 9792.3 9849.6 9820.8 10002.7 10096.1 10106.5 10220.4 16:30:11 INFO - PROCESS | 1652 | -------- Summary: end -------- 16:30:11 INFO - PROCESS | 1652 | 16:30:12 INFO - TEST-INFO | 1652: exit 0 16:30:12 INFO - TEST-OK | dromaeo_css | took 1118945ms 16:30:12 INFO - TEST-START | kraken 16:30:12 INFO - operating with platform_type : mac_ 16:30:12 INFO - Initialising browser for kraken test... 16:30:12 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpOpVIvj/profile http://localhost:49272/getInfo.html) 16:30:14 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 16:30:14 INFO - PROCESS | 1662 | colorDepth:24 16:30:14 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 16:30:14 INFO - PROCESS | 1662 | __metrics 16:30:15 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:30:15 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:30:15 INFO - TEST-INFO | 1662: exit 0 16:30:15 INFO - Browser initialized. 16:30:15 INFO - Running cycle 1/1 for kraken test... 16:30:15 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpOpVIvj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:30:27 INFO - PROCESS | 1663 | RSS: Main: 239165440 16:30:27 INFO - PROCESS | 1663 | 16:30:30 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 16:30:31 INFO - PROCESS | 1663 | RSS: Main: 290115584 16:30:31 INFO - PROCESS | 1663 | 16:30:34 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 16:30:34 INFO - PROCESS | 1663 | RSS: Main: 289677312 16:30:34 INFO - PROCESS | 1663 | 16:30:38 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 16:30:38 INFO - PROCESS | 1663 | RSS: Main: 382820352 16:30:38 INFO - PROCESS | 1663 | 16:30:40 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 16:30:41 INFO - PROCESS | 1663 | RSS: Main: 285478912 16:30:41 INFO - PROCESS | 1663 | 16:30:43 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:30:44 INFO - PROCESS | 1663 | RSS: Main: 287629312 16:30:44 INFO - PROCESS | 1663 | 16:30:46 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 16:30:47 INFO - PROCESS | 1663 | RSS: Main: 325804032 16:30:47 INFO - PROCESS | 1663 | 16:30:50 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 16:30:51 INFO - PROCESS | 1663 | RSS: Main: 326451200 16:30:51 INFO - PROCESS | 1663 | 16:30:53 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 16:30:54 INFO - PROCESS | 1663 | RSS: Main: 323194880 16:30:54 INFO - PROCESS | 1663 | 16:30:56 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:30:56 INFO - PROCESS | 1663 | RSS: Main: 302997504 16:30:56 INFO - PROCESS | 1663 | 16:30:58 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:30:59 INFO - PROCESS | 1663 | RSS: Main: 302886912 16:30:59 INFO - PROCESS | 1663 | 16:31:01 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:31:02 INFO - PROCESS | 1663 | RSS: Main: 305242112 16:31:02 INFO - PROCESS | 1663 | 16:31:05 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:31:06 INFO - PROCESS | 1663 | RSS: Main: 322801664 16:31:06 INFO - PROCESS | 1663 | 16:31:09 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:31:09 INFO - PROCESS | 1663 | RSS: Main: 316715008 16:31:09 INFO - PROCESS | 1663 | 16:31:11 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 16:31:11 INFO - PROCESS | 1663 | RSS: Main: 360337408 16:31:11 INFO - PROCESS | 1663 | 16:31:11 INFO - PROCESS | 1663 | __start_tp_report 16:31:11 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 16:31:11 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 16:31:11 INFO - PROCESS | 1663 | |i|pagename|runs| 16:31:11 INFO - PROCESS | 1663 | |0;ai-astar;112;114;114;113;114;114;114;117;114;113 16:31:11 INFO - PROCESS | 1663 | |1;audio-beat-detection;150;112;114;149;111;111;119;140;150;130 16:31:11 INFO - PROCESS | 1663 | |2;audio-dft;183;182;181;184;182;184;173;184;183;181 16:31:11 INFO - PROCESS | 1663 | |3;audio-fft;79;59;59;88;60;58;59;58;84;58 16:31:11 INFO - PROCESS | 1663 | |4;audio-oscillator;94;96;129;101;68;70;90;69;69;93 16:31:11 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;88;85;88;84;87;88;135;120;86;84 16:31:11 INFO - PROCESS | 1663 | |6;imaging-darkroom;102;143;94;94;94;94;94;94;93;94 16:31:11 INFO - PROCESS | 1663 | |7;imaging-desaturate;81;82;79;77;72;121;138;122;81;80 16:31:11 INFO - PROCESS | 1663 | |8;json-parse-financial;56;56;55;54;55;56;55;56;56;53 16:31:11 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;63;77;103;111;87;68;69;69;69;73 16:31:11 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;88;91;85;86;87;87;96;105;106;106 16:31:11 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;153;156;165;149;148;142;137;134;213;163 16:31:11 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;207;238;182;173;174;175;174;174;177;175 16:31:11 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;62;65;65;68;68;67;67;67;67;68 16:31:11 INFO - PROCESS | 1663 | __end_tp_report 16:31:11 INFO - PROCESS | 1663 | __start_cc_report 16:31:11 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,2112 16:31:11 INFO - PROCESS | 1663 | __end_cc_report 16:31:11 INFO - PROCESS | 1663 | __startTimestamp1478651471732__endTimestamp 16:31:11 INFO - PROCESS | 1663 | 16:31:11 INFO - PROCESS | 1663 | ------- Summary: start ------- 16:31:11 INFO - PROCESS | 1663 | Number of tests: 14 16:31:11 INFO - PROCESS | 1663 | 16:31:11 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.00 stddev:1.29 (1.1%) stddev-sans-first:1.17 16:31:11 INFO - PROCESS | 1663 | Values: 112.0 114.0 114.0 113.0 114.0 114.0 114.0 117.0 114.0 113.0 16:31:11 INFO - PROCESS | 1663 | 16:31:11 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:128.60 Median:135.00 stddev:17.21 (12.7%) stddev-sans-first:16.41 16:31:11 INFO - PROCESS | 1663 | Values: 150.0 112.0 114.0 149.0 111.0 111.0 119.0 140.0 150.0 130.0 16:31:11 INFO - PROCESS | 1663 | 16:31:11 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:181.70 Median:183.00 stddev:3.27 (1.8%) stddev-sans-first:3.43 16:31:11 INFO - PROCESS | 1663 | Values: 183.0 182.0 181.0 184.0 182.0 184.0 173.0 184.0 183.0 181.0 16:31:11 INFO - PROCESS | 1663 | 16:31:11 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:66.20 Median:59.50 stddev:12.25 (20.6%) stddev-sans-first:12.09 16:31:11 INFO - PROCESS | 1663 | Values: 79.0 59.0 59.0 88.0 60.0 58.0 59.0 58.0 84.0 58.0 16:31:11 INFO - PROCESS | 1663 | 16:31:11 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:87.90 Median:80.00 stddev:19.51 (24.4%) stddev-sans-first:20.57 16:31:11 INFO - PROCESS | 1663 | Values: 94.0 96.0 129.0 101.0 68.0 70.0 90.0 69.0 69.0 93.0 16:31:11 INFO - PROCESS | 1663 | 16:31:11 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:94.50 Median:86.50 stddev:17.82 (20.6%) stddev-sans-first:18.74 16:31:11 INFO - PROCESS | 1663 | Values: 88.0 85.0 88.0 84.0 87.0 88.0 135.0 120.0 86.0 84.0 16:31:11 INFO - PROCESS | 1663 | 16:31:11 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:99.60 Median:94.00 stddev:15.46 (16.5%) stddev-sans-first:16.38 16:31:11 INFO - PROCESS | 1663 | Values: 102.0 143.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 16:31:11 INFO - PROCESS | 1663 | 16:31:11 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:93.30 Median:79.50 stddev:23.85 (30.0%) stddev-sans-first:24.88 16:31:11 INFO - PROCESS | 1663 | Values: 81.0 82.0 79.0 77.0 72.0 121.0 138.0 122.0 81.0 80.0 16:31:11 INFO - PROCESS | 1663 | 16:31:11 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:56.00 stddev:1.03 (1.8%) stddev-sans-first:1.05 16:31:11 INFO - PROCESS | 1663 | Values: 56.0 56.0 55.0 54.0 55.0 56.0 55.0 56.0 56.0 53.0 16:31:11 INFO - PROCESS | 1663 | 16:31:11 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:78.90 Median:69.00 stddev:16.26 (23.6%) stddev-sans-first:16.20 16:31:11 INFO - PROCESS | 1663 | Values: 63.0 77.0 103.0 111.0 87.0 68.0 69.0 69.0 69.0 73.0 16:31:11 INFO - PROCESS | 1663 | 16:31:11 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:93.70 Median:87.00 stddev:8.82 (10.1%) stddev-sans-first:9.11 16:31:11 INFO - PROCESS | 1663 | Values: 88.0 91.0 85.0 86.0 87.0 87.0 96.0 105.0 106.0 106.0 16:31:11 INFO - PROCESS | 1663 | 16:31:11 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:156.00 Median:154.50 stddev:22.46 (14.5%) stddev-sans-first:23.80 16:31:11 INFO - PROCESS | 1663 | Values: 153.0 156.0 165.0 149.0 148.0 142.0 137.0 134.0 213.0 163.0 16:31:11 INFO - PROCESS | 1663 | 16:31:11 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.90 Median:176.00 stddev:21.27 (12.1%) stddev-sans-first:21.01 16:31:11 INFO - PROCESS | 1663 | Values: 207.0 238.0 182.0 173.0 174.0 175.0 174.0 174.0 177.0 175.0 16:31:11 INFO - PROCESS | 1663 | 16:31:11 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:1.90 (2.8%) stddev-sans-first:1.17 16:31:11 INFO - PROCESS | 1663 | Values: 62.0 65.0 65.0 68.0 68.0 67.0 67.0 67.0 67.0 68.0 16:31:11 INFO - PROCESS | 1663 | -------- Summary: end -------- 16:31:11 INFO - PROCESS | 1663 | 16:31:12 INFO - TEST-INFO | 1663: exit 0 16:31:12 INFO - TEST-OK | kraken | took 60206ms 16:31:12 INFO - SUITE-END | took 1179s 16:31:12 INFO - Completed test suite (00:19:39) 16:31:12 INFO - javascript benchmark 16:31:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3301.9504707429387, 3340.89977300681, 3348.6651334866515, 3375.223698549332, 3396.9150771230716, 5638.407967163445, 5657.0, 5696.601237913343, 5722.856928576788, 5797.507211886984, 5711.485966263035, 5781.710914454271, 5892.764289428418, 5906.5274778017765, 5918.822435326936, 5127.769250383723, 5132.101882170624, 5162.974185129088, 5214.895702085956, 5252.52727254547, 5348.786048558053, 5350.464953504638, 5356.4107948125675, 5362.088444964365, 5376.677399356032, 5129.692218466904, 5296.761645725913, 5321.467853214686, 5374.6506477078865, 5388.488093631099, 5194.324737784102, 5209.088409528317, 5219.217117432378, 5257.947420525784, 5262.8684282892855, 5248.317718696583, 5250.238715386247, 5261.6842989420375, 5286.629935904488, 5311.654742441729, 5780.942190578083, 5786.1610066540115, 5797.449242321973, 5802.274715660543, 5820.912686309709, 6084.847878803021, 6126.253663788776, 6128.319797384577, 6237.844053898643, 6242.157308763304, 6129.49238426781, 6150.354212807635, 6185.041318595625, 6222.813315600494, 6233.812985610439, 5764.394738552475, 5765.567582431335, 5793.449622285834, 5800.187973683687, 5831.212786273799, 5409.864753381118, 6270.9372906271265, 6285.057241413824, 6313.715882785285, 6317.684115794192, 6166.979614401969, 6176.4441200292185, 6190.7523699051535, 6193.628382297076, 6193.907091393633, 6120.203342909657, 6177.81466556004, 6201.751929922752, 6234.0, 6318.462930650854, 5191.5846732261325, 6165.075238714228, 6269.937300627026, 6278.121063051177, 6278.434940855345, 6289.0, 6291.182146320949, 6296.370362963758, 6299.464545513591, 6309.14826498426, 4850.393700787402, 4921.237208232729, 4978.178600530872, 4984.850454486371, 5011.599072074226, 4368.650507959355, 4426.380306757057, 4428.424304840414, 4432.3351497275025, 4465.307877279027, 5785.739641716133, 5798.478136967608, 5799.623024503394, 5800.767969281266, 5813.040848260074, 6324.33594472583, 6329.873402531924, 6330.208723909512, 6332.746690132435, 6338.334474880164, 5022.694099534147, 5586.748596313176, 5597.328320601554, 5735.913961290584, 5788.044972579477, 5463.180522921514, 5467.371252305956, 5468.617196796186, 5473.917891231635, 5479.0959491683425, 6393.968030159819, 6403.103565500926, 6419.614823110535, 6428.19647544057, 6430.421262086435, 6109.95128740435, 6229.750809967551, 6239.875202496016, 6331.556791024768, 6337.397947195009, 6298.1812364392335, 6320.209973753281, 6332.556721029484, 6402.903956440564, 6414.486841052611, 6221.751129954932, 6263.3736626337, 6283.622982621057, 6286.717097730704, 6287.559870808999, 6104.610739188311, 6301.842453938688, 6323.841903952254, 6353.301136875032, 6374.458171055512, 5057.3931128263375, 6057.636541807506, 6101.368489499298, 6102.420270074336, 6188.4739797117745, 5097.209932460474, 6111.96944015277, 6149.938500614937, 6171.814845554641, 6192.225971753531, 6002.429769171922, 6034.7284372202475, 6058.787942421994, 6061.515078793598, 6067.635941843503, 5994.10088486713, 6072.635641861502, 6097.451229389376, 6108.205933228652, 6137.35557766446, 6008.849778755566, 6017.3681763414215, 6035.577509574287, 6045.0, 6046.697665116639], "value": 5720.1635952088745, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.1572350589257, 505.17404044387416, 515.9871003224916, 516.4628786062495, 516.7390467813755, 28749.712502874965, 29104.563431548544, 30272.09183724486, 33408.49887251693, 37442.81278593603, 38842.80578597103, 39197.60802391982, 39242.018949526275, 39256.80371598138, 39274.912388579745, 8970.10298970101, 9121.133492318218, 9134.406263592879, 9136.634534618608, 9139.268858491305, 9089.772755681095, 9188.402753821014, 9193.862092068624, 9197.126273004054, 9204.263658907304, 9162.267018638493, 9240.630374785, 9242.584083716249, 9249.676261330855, 9250.814983700355, 9101.544922753868, 9169.908300916972, 9176.220021298197, 9182.72451826443, 9194.954025229898, 9049.547522623909, 9097.363184577081, 9106.31702622306, 9108.271338292921, 9123.315751318678, 36623.08442288964, 36968.59704119224, 37004.62995370039, 37051.0, 37071.07322316937, 3292.37444885471, 3297.7856439331367, 3419.264858055505, 3483.5819701635965, 3512.982435087834, 27504.259618813558, 28653.856730716212, 28871.845126194927, 28950.710492895014, 28981.13056608305, 2697.6223328733995, 3065.126438964907, 3177.158053115926, 3186.522021696764, 3284.064041748114, 374239.0, 459516.70241648913, 462289.0, 466114.0, 466875.0, 52436.54289385445, 52672.473275267526, 52723.0, 52748.0, 52776.0, 55415.72292138513, 55463.44536554583, 55563.72218138964, 55578.0, 55640.0, 15216.923915380352, 15412.075275483507, 15429.0, 15437.614059648598, 15543.84456155424, 10180.643677471253, 12148.088893333035, 12186.390680466118, 12199.634010979686, 12217.083718721131, 14013.299335033207, 14424.783628245585, 14447.638809029859, 14580.927095364455, 14664.0, 5518.806841760599, 6468.256150542654, 6617.0, 6619.933800661932, 6634.701438435282, 874.5627186406797, 921.3412410126791, 954.398728800851, 978.7650963768787, 1006.8892421833592, 27425.86287068552, 28980.420391592474, 29189.27026824304, 30332.84833575818, 30532.694673052985, 1031.9277650564538, 1054.6007832181847, 1085.7513859062142, 1089.1123734156638, 1117.8267368557886, 13353.465861365437, 13659.795103073462, 14236.06256117279, 14518.564443066725, 14522.0, 530495.0, 540180.0, 572475.0, 576552.0, 578854.0, 49088.77278068077, 49288.0, 49295.0, 49314.0, 49335.50664493381, 1325.516186591887, 1347.892168626527, 1373.2790285100402, 1374.1892283552752, 1375.931203439844, 22449.438764030376, 27029.459410812065, 27246.910123595626, 27760.0, 27828.582571261853, 45330.0, 45441.318380224984, 45600.7719961398, 45719.77140114278, 45806.54193458023, 520102.0, 521275.0, 521812.0, 521860.0, 523168.0, 23632.881835590713, 23766.405839854142, 24194.75805241925, 24209.39476513031, 25169.119080832083, 42623.7212883614, 42833.143337133704, 43726.34410483909, 43738.0, 44067.0, 35179.0, 35782.64217357793, 35793.82103089468, 36659.81670091632, 37045.0, 35346.64653353434, 35563.288734224654, 36335.454968176025, 36522.72170474021, 36714.63285367112, 35091.64908350986, 35314.11714707153, 35574.466383004794, 35802.92591222163, 35994.64005359913, 30536.236594085327, 31769.20576985594, 31855.903055511008, 31863.522047169776, 31975.36049278955, 19380.709289360388, 23654.88172559126, 24102.51794964126, 24166.39584010414, 24369.26892193237], "value": 18410.051840865486, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.74921431176705, 367.602988772126, 369.0975564744202, 373.3186933845731, 395.64984988285374, 14451.783223251647, 14962.251887405628, 15558.299876505556, 19719.21123155076, 20163.59672806543, 18874.43376698698, 19412.9029354853, 20000.29998950037, 20160.798392016077, 20300.0, 5545.306836645419, 5833.445822646863, 5837.854053648651, 5848.035074212749, 5855.79504717335, 4632.073585282957, 5777.422257774231, 5816.767329306823, 5834.154047663086, 5849.590528662995, 5422.8915421691545, 5578.832635020955, 5788.797392091277, 5803.854903627422, 5814.883702325951, 5609.1305847593685, 5721.370649228582, 5724.313082430135, 5727.627704199213, 5731.312242530881, 4777.020710754322, 5000.774965126578, 5014.899702005976, 5028.0, 5030.069437154101, 24946.750532494625, 25111.748882511307, 25422.237332880042, 25430.0, 25653.48693026129, 2223.1996481266733, 2372.466195106105, 2900.6084178635824, 2959.881604735808, 3162.794418362822, 12026.759464810655, 15076.705872995344, 15447.613809654737, 15450.9227453862, 15986.520404387888, 1618.304129224433, 1837.549800298922, 1860.1443336065427, 1861.2301864220942, 1919.3666090190343, 110254.44872775665, 112569.43715281453, 115588.0, 116198.0, 116329.83670163275, 10185.74535636615, 10410.531526081344, 10481.842772358421, 10704.678859634223, 11053.336799792038, 13421.731565368638, 13466.0, 13484.0, 13491.865081349257, 13517.729645407037, 7348.889766653505, 8101.756947291591, 8144.633491492778, 8396.79008024792, 8533.871991920127, 8156.673733050611, 8470.280026197719, 8477.788055298666, 8484.27883629886, 8685.305175585938, 5440.591955603346, 6337.714802833883, 6472.385123413268, 6480.995445705923, 6493.448056915121, 1759.9120043997748, 1808.52074200337, 1812.7915289741584, 1817.972730409045, 1819.4450692538903, 856.1004623938022, 873.991260087391, 917.1149840404042, 964.9276304277207, 989.1443901025705, 20624.0, 21253.89373053125, 21263.68104478434, 21330.360089197347, 21356.786432135792, 763.9388848892075, 938.4932136646271, 971.6696323250129, 981.8183636027358, 990.5096976996433, 10949.781004379867, 11425.60010399632, 11466.94266528662, 11609.3614851184, 11633.825492617618, 544097.0, 545165.0, 553581.0, 566738.0, 567185.0, 110428.0, 111970.44014779874, 113206.0, 113230.66535601112, 116209.8379016199, 971.9416834989923, 978.8678528398575, 1009.2682804966487, 1009.9697009089739, 1010.3937637417491, 19010.714839277698, 19203.90398048001, 19273.5181620455, 19317.8068219316, 19400.932948687958, 49295.0, 50257.74871125621, 52698.47301527089, 53201.0, 54161.458385415644, 71394.28605714084, 73712.0, 75155.248447517, 75594.11014724676, 76071.61964190143, 7366.5635201135265, 7574.697012119454, 7872.488288261245, 7920.485168464032, 8006.079300880473, 11437.256578322382, 11513.88486115128, 12818.61544153677, 12850.100492965403, 13206.933965330496, 10986.890131098906, 11023.503942322775, 11070.446477675923, 11077.411180173503, 11131.610393636183, 10923.617673381394, 11126.388048657078, 11161.995420412202, 11254.83117753218, 11316.83024754613, 10931.726706832393, 11188.720281993015, 11230.66308010761, 11460.541578337106, 11504.424778760862, 6132.8773424532155, 7472.40220782347, 7653.6555854985545, 7853.764387068397, 8145.918540814516, 9533.761655958657, 9855.852162217428, 10046.0, 10707.625233116803, 10919.344839309666], "value": 9482.328753842668, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1319.7756381415159, 1470.536780914012, 1504.8645621894032, 1668.416054380967, 1851.1484717030166, 7462.776116716501, 7874.4094192935545, 8006.239407256316, 8346.83306333873, 8366.28886544643, 8239.91760082399, 8323.33413326934, 8330.37522185837, 8333.250007499324, 8339.416240863142, 2284.7829456201634, 2314.884255787212, 2316.1661801751357, 2318.6522021696796, 2319.663648770927, 2284.189112864934, 2332.2769941318247, 2347.471818840764, 2350.388898886293, 2353.776391242829, 2274.9658755118685, 2287.3252390544744, 2306.5732839424677, 2309.4457330240707, 2310.3069079276233, 2255.4474153832252, 2266.8866556672183, 2288.8283378746496, 2291.724993000834, 2301.413139649387, 2283.3264187064856, 2304.2511183865313, 2306.489249541553, 2310.688057112285, 2312.5721741477882, 7985.401094917905, 8269.29710974568, 8350.248477637042, 8353.95823020881, 8359.331253499766, 5027.899442011139, 5234.293370394986, 5238.423773384925, 5243.86890327741, 5258.789648414059, 2873.5115030444877, 3431.0736101252523, 3535.8762443314617, 3618.8190590470635, 3622.6920711739535, 1062.659948816379, 1094.0153861524611, 1701.2004357951744, 1737.4179649817327, 1826.4520643806804, 10423.322484038437, 10852.077573406192, 10936.398498082603, 10953.233273670847, 10959.890401095967, 3726.925461490755, 4276.422682937764, 4426.380306757057, 4443.4445694288215, 4457.398251236042, 8655.783605409915, 8690.695825646071, 8695.739127826175, 8703.695370661997, 8707.303415726727, 5025.120603894304, 5041.294218809369, 5042.193249080129, 5049.596032317406, 5056.544910958031, 4059.5331536873045, 4186.727862688916, 4727.669063165546, 4755.548222918817, 4759.666823322404, 3332.9333413331597, 3517.648235176462, 3555.6266592007983, 3644.9817750911075, 3652.7625704329134, 1036.657902892044, 1130.4912789244872, 1134.6936327191613, 1135.3244819332483, 1141.5034460009756, 795.1849354411659, 886.1581497577328, 893.9374243803002, 899.4153800029902, 923.1645360948219, 5282.124845679728, 5436.134659479743, 5632.549396048388, 5728.1407788831175, 5811.0121279381765, 801.531104303977, 810.3962547901851, 897.3045889435609, 960.3037797596686, 1013.6756238003768, 3660.5790334111384, 3776.4712940188397, 3793.165503589206, 3862.304785138702, 3925.4896863407575, 9697.418154910727, 9988.101070903653, 10138.290319677699, 10302.278840481244, 10323.587056517656, 9824.312298139062, 10269.948650256702, 10277.845832312521, 10282.897171028193, 10296.279260451696, 7731.613419329011, 7855.800375894759, 7874.645640946172, 7887.0, 7996.0804507481225, 4839.806407743651, 5537.889242215214, 5571.4428557143965, 5580.9162862557905, 5664.971675141598, 8374.623141958504, 8806.339524535557, 9299.814003719752, 9517.857232141661, 9631.518424078908, 9565.33042687033, 9635.325527213308, 9645.180159686504, 9668.0, 9747.610095596097, 6256.249250090018, 6444.291127976013, 6460.030995350735, 6698.095757072733, 6851.0, 7711.575863327571, 7904.446688731734, 7974.0, 8072.273495385443, 8078.7172448964, 6061.272647282354, 7003.859922801617, 7226.313500217471, 7256.528325658815, 7322.524035937647, 5784.797532086357, 6997.825054373681, 7027.4378049756915, 7067.399271061811, 7099.467539934422, 6589.604623722592, 6683.298253683488, 6765.661716914036, 6784.3215678433735, 6784.626845523544, 4818.759062046814, 4882.975585122052, 4896.436909755424, 4926.113299606105, 4990.101781679333, 4870.464248932543, 4883.340748998839, 4921.483244259444, 4981.252812078217, 4986.202207646759], "value": 4393.35718847306, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.84573099122963, 295.19706398595827, 303.3553698390919, 308.80699562773265, 309.979851309665, 4639.6984196027315, 4696.859094227174, 4993.675411098276, 5114.232865070241, 5125.564841844281, 4516.232240519112, 4588.472325682547, 4788.749287834712, 4891.755412229392, 4964.304997300381, 3223.7904536205187, 3580.946285805715, 3926.607339266079, 3967.3255546557157, 3973.46358241638, 3296.8516416761304, 3555.715542756573, 3631.855965370913, 3943.4508941952186, 3948.447217389568, 3364.764466487347, 3691.630836916314, 3696.2792255510185, 3934.586868378822, 3938.23204475126, 3688.981555092207, 3732.384156614142, 3902.902427439308, 3916.432117342998, 3971.9801400992956, 3433.5193072969805, 3436.8453419596176, 3451.6030656474577, 3461.2039230977016, 3467.6532346765375, 4355.7822108894425, 5028.647994640377, 5036.597072234249, 5046.7224302663335, 5058.342415485963, 3872.4965754452023, 4207.768572728499, 4339.392485052095, 4458.598726114666, 4466.665000125004, 4255.085156691295, 4952.83608352037, 5087.389513258396, 5317.069512835238, 5367.162478827657, 3140.9685903140903, 3228.0961330827404, 3799.525059367579, 3807.638274363931, 3820.1977584707247, 4539.001419687664, 5528.004959107359, 5572.275604171432, 5670.206171136044, 5677.460641239076, 3138.2468207630004, 3701.4447832824753, 3751.9624803751612, 3759.661630453272, 3770.490983717218, 3206.0381885434276, 3610.891673249807, 3796.4115562087923, 3802.1825307559006, 3814.6948244140403, 3927.7446965947693, 4138.337865941435, 4246.660267178619, 4410.8090815479645, 4438.667099967515, 4648.651351148677, 5020.974895125574, 5048.949510504848, 5394.514493695587, 5429.226687452262, 1073.9087177589836, 1217.7442737025146, 1225.6997035726126, 1229.932353720554, 1237.3751255615857, 770.3990887108065, 779.4855395438983, 798.22572105058, 801.4750338528273, 831.6631764135535, 5059.620528460307, 5113.335266415343, 5147.996140752517, 5158.045761534128, 5183.9113786105345, 4967.90064198714, 5572.275604171432, 5580.525655319262, 5587.469190426903, 5592.133219351007, 4709.139889743532, 4931.630127740362, 4949.752512374367, 5130.948690513122, 5145.742712864342, 2734.4394399148127, 2763.701060501561, 2778.8054836161677, 2788.2889863084783, 2790.7906906982057, 4942.654014219042, 5325.328525120812, 5421.593380496479, 5428.83713488596, 5477.0, 4407.988799453789, 4520.412346394947, 4615.538446155358, 5396.676199428047, 5419.34967803866, 4804.855854324376, 4956.884700942259, 4971.81240254287, 5040.7902103495635, 5053.823116190915, 4760.714357138444, 5242.58059355243, 5249.375312343828, 5275.8681032972945, 5292.973535132453, 4113.424120623175, 4525.886852828706, 4688.437387513521, 4825.734584597881, 5556.833295001156, 4862.5380588844, 5071.315372424676, 5697.173909782975, 5798.17357532376, 5853.9121913172185, 3293.802371857696, 3796.240751849586, 3809.9428508572946, 3812.6949844011856, 3819.980900095482, 4588.839390621312, 5061.584105007753, 5275.971185618768, 5609.242752228397, 5668.979583674978, 5425.077736784835, 5453.372862120907, 5456.918146227889, 5466.3986961435, 5480.205370221375, 4798.448178459522, 5455.9633669602645, 5470.316210473691, 5493.593971737549, 5494.890102198014, 4405.581469760367, 4842.813510689763, 4956.578690811177, 5331.587129410632, 5346.946530534645, 4637.704336353527, 5126.79492820283, 5129.487051294841, 5321.275107424741, 5330.600204984719, 4804.327394164749, 5246.868828279324, 5300.9734951325, 5303.0, 5317.441668624893], "value": 3859.578404949416, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.2810019544494, 670.6828501053504, 677.1366507702676, 703.4899697719154, 805.0741647105827, 14033.92983035086, 14677.192754398517, 14923.776143357858, 14983.325750341233, 15147.0, 13313.068085234037, 13369.732605347912, 13539.052266341336, 13793.172409655428, 13807.447702091904, 796.74902405742, 804.951702897825, 812.4556547113418, 813.5769399912042, 815.902091748991, 666.0874601795523, 685.7291369908908, 688.30481213974, 815.3436483630675, 819.0008190008181, 843.7763992541982, 867.1848462445313, 871.2724874729577, 873.3406278259905, 873.8427083125035, 857.2284943550854, 859.1923591823665, 859.5229647545609, 860.3332417376521, 860.6514361683528, 505.6182582150453, 635.8219698484431, 636.522608043967, 636.6402982314977, 637.2066776862789, 11795.410229488578, 12982.09125361225, 13412.597622071353, 13432.261225632587, 13797.586072417844, 13273.734525309535, 13495.932520337434, 13586.524471643444, 13713.520026799015, 13790.103643263255, 11266.211365204439, 11310.773784524263, 11333.206675532716, 11347.886521134766, 11707.356095414749, 3589.228315912065, 3594.730395220369, 3616.168281295317, 3640.672339489459, 3758.8120593970466, 71150.28849711489, 87527.12472875253, 88144.0, 89417.0, 91498.99999999933, 10355.585776569003, 10461.22465145756, 10703.25077244601, 10748.838767418494, 10774.353538787698, 19748.40754777359, 20140.0, 20277.29029483961, 20285.391438256876, 20294.18823247083, 7392.963035184897, 7508.62456877154, 7619.771406857803, 7714.074311082706, 7794.181610930884, 3609.9819500902327, 4269.466316710411, 4293.935590966138, 4372.781360931941, 4487.012857171417, 3315.3700796849002, 3397.3884700753606, 3411.9829400852836, 3433.2446861690387, 3433.261848702491, 577.9508741756998, 593.4866340615337, 628.7704987679464, 633.4678869749432, 638.9361063893573, 566.8016194331951, 617.6541136963315, 630.854903372227, 660.089077073645, 664.166471078793, 8533.146685331541, 8754.606042727964, 8893.911060889437, 9071.0, 9076.455412675261, 582.6329412470202, 616.4236438929615, 626.9686515674198, 650.8047585724264, 651.1014799576554, 4158.771267580251, 4256.593527262541, 4391.231534481453, 4400.1199760047475, 4511.458624965026, 75610.62194689101, 77350.61324693453, 104253.47873260586, 118391.0, 119629.0, 67409.66295168492, 81373.59313203515, 103213.0, 104334.47832760788, 109572.4521377388, 59316.11025834616, 83570.74643880347, 90980.0, 91822.0, 92068.53965730128, 7112.253213412517, 8232.62953167097, 8556.957215213884, 8702.390832641655, 8720.607572659372, 37199.490526986454, 41570.12934418019, 51397.22904156515, 53778.462215377345, 54020.0, 49360.75319623379, 49838.75403114951, 68582.65708671424, 74279.0, 76812.61593691996, 11370.488328025092, 11471.13966452536, 11491.54033838637, 11549.249298795552, 11815.82276265874, 11352.886471135183, 11838.171328006956, 11863.110266729856, 11920.284782913053, 11982.58060967862, 11414.714632134263, 11415.086793056702, 11579.88420115788, 11749.530018799152, 11994.700132496757, 11362.03422709079, 11452.427378631202, 11726.765464690829, 11956.163068585116, 12023.398830058288, 11464.082873370277, 11478.311301321948, 11506.252093613888, 11563.190576659554, 11693.298402095561, 7392.815179620553, 9536.189423898768, 9749.122578967927, 9792.265580081379, 9849.60601575929, 9820.754481137743, 10002.749931251778, 10096.141827944413, 10106.545205465918, 10220.386776793417], "value": 6943.417942912123, "unit": "score"}], "name": "dromaeo_css", "value": 6999.319994716834, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 114.0, 113.0, 114.0, 114.0, 114.0, 117.0, 114.0, 113.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 112.0, 114.0, 149.0, 111.0, 111.0, 119.0, 140.0, 150.0, 130.0], "value": 128.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 181.0, 184.0, 182.0, 184.0, 173.0, 184.0, 183.0, 181.0], "value": 181.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 59.0, 88.0, 60.0, 58.0, 59.0, 58.0, 84.0, 58.0], "value": 66.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 129.0, 101.0, 68.0, 70.0, 90.0, 69.0, 69.0, 93.0], "value": 87.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 84.0, 87.0, 88.0, 135.0, 120.0, 86.0, 84.0], "value": 94.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 143.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0], "value": 99.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 79.0, 77.0, 72.0, 121.0, 138.0, 122.0, 81.0, 80.0], "value": 93.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 54.0, 55.0, 56.0, 55.0, 56.0, 56.0, 53.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 77.0, 103.0, 111.0, 87.0, 68.0, 69.0, 69.0, 69.0, 73.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 91.0, 85.0, 86.0, 87.0, 87.0, 96.0, 105.0, 106.0, 106.0], "value": 93.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 156.0, 165.0, 149.0, 148.0, 142.0, 137.0, 134.0, 213.0, 163.0], "value": 156.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 238.0, 182.0, 173.0, 174.0, 175.0, 174.0, 174.0, 177.0, 175.0], "value": 184.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 65.0, 68.0, 68.0, 67.0, 67.0, 67.0, 67.0, 68.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1500.8000000000002, "alertThreshold": 2.0}]} 16:31:12 INFO - Return code: 0 16:31:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:31:13 INFO - # TBPL SUCCESS # 16:31:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:31:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:31:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:31:13 INFO - 2016-11-08 16:31:13.038 screenresolution[1667:9723] starting screenresolution argv=screenresolution get 16:31:13 INFO - 2016-11-08 16:31:13.040 screenresolution[1667:9723] Display 0: 1600x1200x32@60 16:31:13 INFO - 2016-11-08 16:31:13.047 screenresolution[1668:9726] starting screenresolution argv=screenresolution list 16:31:13 INFO - Available Modes on Display 0 16:31:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:31:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:31:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:31:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:31:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:31:13 INFO - Intel Iris: 16:31:13 INFO - Chipset Model: Intel Iris 16:31:13 INFO - Type: GPU 16:31:13 INFO - Bus: Built-In 16:31:13 INFO - VRAM (Dynamic, Max): 1536 MB 16:31:13 INFO - Vendor: Intel (0x8086) 16:31:13 INFO - Device ID: 0x0a2e 16:31:13 INFO - Revision ID: 0x0009 16:31:13 INFO - Displays: 16:31:13 INFO - Display: 16:31:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:31:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:31:13 INFO - Display Serial Number: 1600x1200 60 16:31:13 INFO - Main Display: Yes 16:31:13 INFO - Mirror: Off 16:31:13 INFO - Online: Yes 16:31:13 INFO - Rotation: Supported 16:31:13 INFO - Return code: 0 16:31:13 INFO - Running post-action listener: _resource_record_post_action 16:31:13 INFO - [mozharness: 2016-11-09 00:31:13.296740Z] Finished run-tests step (success) 16:31:13 INFO - Running post-run listener: _resource_record_post_run 16:31:13 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 38843392; Write bytes: 704633856; Read time: 373; Write time: 1933 16:31:13 INFO - TinderboxPrint: CPU usage
53.5% 16:31:13 INFO - TinderboxPrint: I/O read bytes / time
38,843,392 / 373 16:31:13 INFO - TinderboxPrint: I/O write bytes / time
704,633,856 / 1,933 16:31:13 INFO - TinderboxPrint: CPU idle
2,257.9 (46.5%) 16:31:13 INFO - TinderboxPrint: CPU system
973.9 (20.0%) 16:31:13 INFO - TinderboxPrint: CPU user
1,625.9 (33.5%) 16:31:13 INFO - TinderboxPrint: Swap in / out
442,228,736 / 0 16:31:13 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 394844672; Write bytes: 389477888; Read time: 29517; Write time: 815 16:31:13 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 36832256; Write bytes: 297088512; Read time: 351; Write time: 1075 16:31:13 INFO - Running post-run listener: _upload_blobber_files 16:31:13 INFO - Blob upload gear active. 16:31:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:31:13 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 16:31:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:31:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:31:14 INFO - (blobuploader) - INFO - Open directory for files ... 16:31:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:31:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:31:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:31:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:31:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:31:15 INFO - (blobuploader) - INFO - Done attempting. 16:31:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:31:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:31:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:31:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:31:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:31:16 INFO - (blobuploader) - INFO - Done attempting. 16:31:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:31:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:31:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:31:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:31:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:31:18 INFO - (blobuploader) - INFO - Done attempting. 16:31:18 INFO - (blobuploader) - INFO - Iteration through files over. 16:31:18 INFO - Return code: 0 16:31:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:31:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:31:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc0b29dca3db9a4b1043ae4d492bdc8455d54c437e94d6bbdc37ec29f28258d3ba6ebcd25d53584512279795f555a9a7de4281959bbb8caeb5962fd78774e93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1847f0a3f677a5e6beaf370df9050fd55f94358b23114d7061d5ac995c5247abd31bb677519ee7f59411e9da41ba5ffb580965feee98f14578d345e3fa3f6820", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9398fba877a8388f15e527d58dcbd50ab1bf9c68d345670994b90811d4c2baf7e70a6e7b9e90a31a19be7c54b9b1737aea6cf050f6cda84692de0db4383084"} 16:31:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:31:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:31:18 INFO - Contents: 16:31:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc0b29dca3db9a4b1043ae4d492bdc8455d54c437e94d6bbdc37ec29f28258d3ba6ebcd25d53584512279795f555a9a7de4281959bbb8caeb5962fd78774e93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1847f0a3f677a5e6beaf370df9050fd55f94358b23114d7061d5ac995c5247abd31bb677519ee7f59411e9da41ba5ffb580965feee98f14578d345e3fa3f6820", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9398fba877a8388f15e527d58dcbd50ab1bf9c68d345670994b90811d4c2baf7e70a6e7b9e90a31a19be7c54b9b1737aea6cf050f6cda84692de0db4383084"} 16:31:18 INFO - Running post-run listener: copy_logs_to_upload_dir 16:31:18 INFO - Copying logs to upload dir... 16:31:18 INFO - mkdir: /builds/slave/test/build/upload/logs 16:31:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.204212 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-08 16:31:18.368906) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-08 16:31:18.372676) ========= 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.3afePF6PdJ/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.ObcnyCNFJF/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc0b29dca3db9a4b1043ae4d492bdc8455d54c437e94d6bbdc37ec29f28258d3ba6ebcd25d53584512279795f555a9a7de4281959bbb8caeb5962fd78774e93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1847f0a3f677a5e6beaf370df9050fd55f94358b23114d7061d5ac995c5247abd31bb677519ee7f59411e9da41ba5ffb580965feee98f14578d345e3fa3f6820", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9398fba877a8388f15e527d58dcbd50ab1bf9c68d345670994b90811d4c2baf7e70a6e7b9e90a31a19be7c54b9b1737aea6cf050f6cda84692de0db4383084"} build_url:https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011068 build_url: 'https://queue.taskcluster.net/v1/task/TQVTNvYCSU-WsWLU7iXU4g/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffc0b29dca3db9a4b1043ae4d492bdc8455d54c437e94d6bbdc37ec29f28258d3ba6ebcd25d53584512279795f555a9a7de4281959bbb8caeb5962fd78774e93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1847f0a3f677a5e6beaf370df9050fd55f94358b23114d7061d5ac995c5247abd31bb677519ee7f59411e9da41ba5ffb580965feee98f14578d345e3fa3f6820", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9e9398fba877a8388f15e527d58dcbd50ab1bf9c68d345670994b90811d4c2baf7e70a6e7b9e90a31a19be7c54b9b1737aea6cf050f6cda84692de0db4383084"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-08 16:31:18.405717) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 16:31:18.406036) ========= 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.3afePF6PdJ/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.ObcnyCNFJF/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.005013 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 16:31:18.454290) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-08 16:31:18.454634) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-08 16:31:18.454969) ========= ========= Total master_lag: 1.92 =========