builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0004 starttime: 1461968165.4 results: success (0) buildid: 20160429140836 builduid: ff0606aa01fa426aa68dbe9a89f6f859 revision: 7c17eb91821968d66808c032fb3f38885d0dedda ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 15:16:05.403558) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 15:16:05.403982) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-29 15:16:05.404297) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-29 15:16:05.428629) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 15:16:05.428970) ========= 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.c1H1xDDWB1/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.iyamyUbuxl/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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-04-29 15:16:05-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.7M=0.001s 2016-04-29 15:16:05 (14.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123704 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 15:16:05.573265) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 15:16:05.573590) ========= 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.c1H1xDDWB1/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.iyamyUbuxl/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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.027134 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 15:16:05.622074) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-29 15:16:05.625485) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7c17eb91821968d66808c032fb3f38885d0dedda --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 7c17eb91821968d66808c032fb3f38885d0dedda --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.c1H1xDDWB1/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.iyamyUbuxl/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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-04-29 15:16:05,696 truncating revision to first 12 chars 2016-04-29 15:16:05,696 Setting DEBUG logging. 2016-04-29 15:16:05,696 attempt 1/10 2016-04-29 15:16:05,696 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/7c17eb918219?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-29 15:16:06,747 unpacking tar archive at: mozilla-aurora-7c17eb918219/testing/mozharness/ program finished with exit code 0 elapsedTime=1.299750 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-29 15:16:06.945712) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 15:16:06.946066) ========= script_repo_revision: 7c17eb91821968d66808c032fb3f38885d0dedda ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 15:16:06.946497) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-29 15:16:06.946763) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-29 15:16:06.962778) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-04-29 15:16:06.963167) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.c1H1xDDWB1/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.iyamyUbuxl/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 15:16:07 INFO - MultiFileLogger online at 20160429 15:16:07 in /builds/slave/test 15:16:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:16:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:16:07 INFO - {'append_to_log': False, 15:16:07 INFO - 'base_work_dir': '/builds/slave/test', 15:16:07 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:16:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:16:07 INFO - 'branch': 'Mozilla-Aurora', 15:16:07 INFO - 'buildbot_json_path': 'buildprops.json', 15:16:07 INFO - 'config_files': ('talos/mac_config.py',), 15:16:07 INFO - 'default_actions': ('clobber', 15:16:07 INFO - 'read-buildbot-config', 15:16:07 INFO - 'download-and-extract', 15:16:07 INFO - 'populate-webroot', 15:16:07 INFO - 'create-virtualenv', 15:16:07 INFO - 'install', 15:16:07 INFO - 'run-tests'), 15:16:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:16:07 INFO - 'download_minidump_stackwalk': True, 15:16:07 INFO - 'download_symbols': 'ondemand', 15:16:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:16:07 INFO - 'tooltool.py': '/tools/tooltool.py', 15:16:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:16:07 INFO - '/tools/misc-python/virtualenv.py')}, 15:16:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:16:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:16:07 INFO - 'installer_path': 'installer.exe', 15:16:07 INFO - 'log_level': 'info', 15:16:07 INFO - 'log_name': 'talos', 15:16:07 INFO - 'log_to_console': True, 15:16:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:16:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:16:07 INFO - 'opt_config_files': (), 15:16:07 INFO - 'pip_index': False, 15:16:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:16:07 INFO - 'cmd': ('bash', 15:16:07 INFO - '-c', 15:16:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:16:07 INFO - 'enabled': True, 15:16:07 INFO - 'halt_on_failure': False, 15:16:07 INFO - 'name': 'check_screen_resolution'},), 15:16:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:16:07 INFO - 'cmd': ('bash', 15:16:07 INFO - '-c', 15:16:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:16:07 INFO - 'enabled': True, 15:16:07 INFO - 'halt_on_failure': False, 15:16:07 INFO - 'name': 'check_screen_resolution'},), 15:16:07 INFO - 'run_cmd_checks_enabled': True, 15:16:07 INFO - 'sps_profile': False, 15:16:07 INFO - 'sps_profile_interval': 0, 15:16:07 INFO - 'suite': 'dromaeojs-e10s', 15:16:07 INFO - 'system_bits': '32', 15:16:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:16:07 INFO - 'title': 't-yosemite-r7-0004', 15:16:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:16:07 INFO - 'use_talos_json': True, 15:16:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:16:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:16:07 INFO - 'work_dir': 'build'} 15:16:07 INFO - ##### 15:16:07 INFO - ##### Running clobber step. 15:16:07 INFO - ##### 15:16:07 INFO - Running pre-action listener: _resource_record_pre_action 15:16:07 INFO - Running main action method: clobber 15:16:07 INFO - rmtree: /builds/slave/test/build 15:16:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:16:08 INFO - Running post-action listener: _resource_record_post_action 15:16:08 INFO - ##### 15:16:08 INFO - ##### Running read-buildbot-config step. 15:16:08 INFO - ##### 15:16:08 INFO - Running pre-action listener: _resource_record_pre_action 15:16:08 INFO - Running main action method: read_buildbot_config 15:16:08 INFO - Using buildbot properties: 15:16:08 INFO - { 15:16:08 INFO - "project": "", 15:16:08 INFO - "product": "firefox", 15:16:08 INFO - "script_repo_revision": "production", 15:16:08 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 15:16:08 INFO - "repository": "", 15:16:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 15:16:08 INFO - "buildid": "20160429140836", 15:16:08 INFO - "pgo_build": "False", 15:16:08 INFO - "basedir": "/builds/slave/test", 15:16:08 INFO - "buildnumber": 276, 15:16:08 INFO - "slavename": "t-yosemite-r7-0004", 15:16:08 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:16:08 INFO - "platform": "yosemite_r7", 15:16:08 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:16:08 INFO - "branch": "mozilla-aurora", 15:16:08 INFO - "repo_path": "releases/mozilla-aurora", 15:16:08 INFO - "revision": "7c17eb91821968d66808c032fb3f38885d0dedda", 15:16:08 INFO - "stage_platform": "macosx64", 15:16:08 INFO - "builduid": "ff0606aa01fa426aa68dbe9a89f6f859", 15:16:08 INFO - "slavebuilddir": "test" 15:16:08 INFO - } 15:16:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 15:16:08 INFO - Running post-action listener: _resource_record_post_action 15:16:08 INFO - ##### 15:16:08 INFO - ##### Running download-and-extract step. 15:16:08 INFO - ##### 15:16:08 INFO - Running pre-action listener: _resource_record_pre_action 15:16:08 INFO - Running main action method: download_and_extract 15:16:08 INFO - mkdir: /builds/slave/test/build/tests 15:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:16:08 INFO - https://queue.taskcluster.net/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:16:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:16:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:16:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 15:16:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 15:16:10 INFO - Downloaded 1199 bytes. 15:16:10 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 15:16:10 INFO - Using the following test package requirements: 15:16:10 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:16:10 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:16:10 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 15:16:10 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:16:10 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 15:16:10 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:16:10 INFO - u'jsshell-mac.zip'], 15:16:10 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:16:10 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 15:16:10 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:16:10 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:16:10 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 15:16:10 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:16:10 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 15:16:10 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:16:10 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 15:16:10 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:16:10 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 15:16:10 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:16:10 INFO - https://queue.taskcluster.net/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:16:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:16:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:16:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:16:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:16:15 INFO - Downloaded 18110399 bytes. 15:16:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:16:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:16:18 INFO - Return code: 0 15:16:18 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 15:16:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:16:18 INFO - https://queue.taskcluster.net/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:16:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:16:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:16:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:16:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 15:16:19 INFO - Downloaded 18110399 bytes. 15:16:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:16:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:16:23 INFO - Return code: 0 15:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:16:23 INFO - https://queue.taskcluster.net/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:16:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:16:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:16:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:16:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 15:16:25 INFO - Downloaded 10840145 bytes. 15:16:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:16:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:16:25 INFO - Return code: 0 15:16:25 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:16:25 INFO - https://queue.taskcluster.net/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 15:16:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:16:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:16:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 15:16:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 15:16:33 INFO - Downloaded 114066409 bytes. 15:16:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:16:33 INFO - mkdir: /builds/slave/test/properties 15:16:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:16:33 INFO - Writing to file /builds/slave/test/properties/build_url 15:16:33 INFO - Contents: 15:16:33 INFO - build_url:https://queue.taskcluster.net/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:16:34 INFO - Running post-action listener: _resource_record_post_action 15:16:34 INFO - Running post-action listener: set_extra_try_arguments 15:16:34 INFO - ##### 15:16:34 INFO - ##### Running populate-webroot step. 15:16:34 INFO - ##### 15:16:34 INFO - Running pre-action listener: _resource_record_pre_action 15:16:34 INFO - Running main action method: populate_webroot 15:16:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:16:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:16:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:16:35 INFO - '--tppagecycles', 15:16:35 INFO - '7'], 15:16:35 INFO - 'tests': ['tsvgm']}}, 15:16:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:16:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:16:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:16:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:16:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:16:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:16:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:35 INFO - 'talos_options': ['--disable-e10s'], 15:16:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:16:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:16:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:35 INFO - 'talos_options': ['--disable-e10s'], 15:16:35 INFO - 'tests': ['damp', 'tps']}, 15:16:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:35 INFO - 'tests': ['damp', 'tps']}, 15:16:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:16:35 INFO - 'tests': ['dromaeo_dom']}, 15:16:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:16:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:16:35 INFO - 'tests': ['a11yr', 15:16:35 INFO - 'ts_paint', 15:16:35 INFO - 'tpaint', 15:16:35 INFO - 'sessionrestore', 15:16:35 INFO - 'sessionrestore_no_auto_restore', 15:16:35 INFO - 'tabpaint']}, 15:16:35 INFO - 'other-e10s': {'tests': ['a11yr', 15:16:35 INFO - 'ts_paint', 15:16:35 INFO - 'tpaint', 15:16:35 INFO - 'sessionrestore', 15:16:35 INFO - 'sessionrestore_no_auto_restore', 15:16:35 INFO - 'tabpaint']}, 15:16:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:16:35 INFO - 'ts_paint', 15:16:35 INFO - 'tpaint', 15:16:35 INFO - 'sessionrestore', 15:16:35 INFO - 'sessionrestore_no_auto_restore', 15:16:35 INFO - 'tabpaint']}, 15:16:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:16:35 INFO - 'ts_paint', 15:16:35 INFO - 'tpaint', 15:16:35 INFO - 'sessionrestore', 15:16:35 INFO - 'sessionrestore_no_auto_restore', 15:16:35 INFO - 'tabpaint']}, 15:16:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:16:35 INFO - 'tests': ['a11yr', 15:16:35 INFO - 'ts_paint', 15:16:35 INFO - 'tpaint', 15:16:35 INFO - 'sessionrestore', 15:16:35 INFO - 'sessionrestore_no_auto_restore', 15:16:35 INFO - 'tabpaint']}, 15:16:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:16:35 INFO - 'tests': ['a11yr', 15:16:35 INFO - 'ts_paint', 15:16:35 INFO - 'tpaint', 15:16:35 INFO - 'sessionrestore', 15:16:35 INFO - 'sessionrestore_no_auto_restore', 15:16:35 INFO - 'tabpaint']}, 15:16:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:16:35 INFO - 'tests': ['tsvgx', 15:16:35 INFO - 'tsvgr_opacity', 15:16:35 INFO - 'tart', 15:16:35 INFO - 'tscrollx', 15:16:35 INFO - 'cart']}, 15:16:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:16:35 INFO - 'tsvgr_opacity', 15:16:35 INFO - 'tart', 15:16:35 INFO - 'tscrollx', 15:16:35 INFO - 'cart']}, 15:16:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:35 INFO - 'talos_options': ['--disable-e10s'], 15:16:35 INFO - 'tests': ['tp5o']}, 15:16:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:35 INFO - 'tests': ['tp5o']}, 15:16:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:35 INFO - 'talos_options': ['--disable-e10s', 15:16:35 INFO - '--xperf_path', 15:16:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:16:35 INFO - 'tests': ['tp5n']}, 15:16:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:16:35 INFO - 'talos_options': ['--xperf_path', 15:16:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:16:35 INFO - 'tests': ['tp5n']}}, 15:16:35 INFO - 'talos.zip': {'path': '', 15:16:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:16:35 INFO - Running post-action listener: _resource_record_post_action 15:16:35 INFO - ##### 15:16:35 INFO - ##### Running create-virtualenv step. 15:16:35 INFO - ##### 15:16:35 INFO - Running pre-action listener: _resource_record_pre_action 15:16:35 INFO - Running main action method: create_virtualenv 15:16:35 INFO - Creating virtualenv /builds/slave/test/build/venv 15:16:35 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 15:16:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:16:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:16:35 INFO - Using real prefix '/tools/python27' 15:16:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:16:35 INFO - Installing distribute.............................................................................................................................................................................................done. 15:16:38 INFO - Installing pip.................done. 15:16:38 INFO - Return code: 0 15:16:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:16: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:16:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e02ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e03c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd840c88e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decbe00>, '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 0x10dd34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec6b20>, '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.c1H1xDDWB1/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.iyamyUbuxl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 15:16: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', 'pip>=1.5'] in /builds/slave/test/build 15:16: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 pip>=1.5 15:16:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c1H1xDDWB1/Render', 15:16:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:38 INFO - 'HOME': '/Users/cltbld', 15:16:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:38 INFO - 'LOGNAME': 'cltbld', 15:16:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:38 INFO - 'MOZ_NO_REMOTE': '1', 15:16:38 INFO - 'NO_EM_RESTART': '1', 15:16:38 INFO - 'PAGER': '/bin/cat', 15:16:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:38 INFO - 'PWD': '/builds/slave/test', 15:16:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:38 INFO - 'SHELL': '/bin/bash', 15:16:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iyamyUbuxl/Listeners', 15:16:38 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 15:16:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:38 INFO - 'USER': 'cltbld', 15:16:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:38 INFO - 'XPC_FLAGS': '0x0', 15:16:38 INFO - 'XPC_SERVICE_NAME': '0', 15:16:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:38 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 15:16:38 INFO - Cleaning up... 15:16:38 INFO - Return code: 0 15:16:38 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:16: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:16:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16: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', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e02ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e03c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd840c88e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decbe00>, '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 0x10dd34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec6b20>, '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.c1H1xDDWB1/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.iyamyUbuxl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 15:16: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', 'psutil>=0.7.1'] in /builds/slave/test/build 15:16: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 psutil>=0.7.1 15:16:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c1H1xDDWB1/Render', 15:16:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:38 INFO - 'HOME': '/Users/cltbld', 15:16:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:38 INFO - 'LOGNAME': 'cltbld', 15:16:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:38 INFO - 'MOZ_NO_REMOTE': '1', 15:16:38 INFO - 'NO_EM_RESTART': '1', 15:16:38 INFO - 'PAGER': '/bin/cat', 15:16:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:38 INFO - 'PWD': '/builds/slave/test', 15:16:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:38 INFO - 'SHELL': '/bin/bash', 15:16:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iyamyUbuxl/Listeners', 15:16:38 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 15:16:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:38 INFO - 'USER': 'cltbld', 15:16:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:38 INFO - 'XPC_FLAGS': '0x0', 15:16:38 INFO - 'XPC_SERVICE_NAME': '0', 15:16:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:39 INFO - Downloading/unpacking psutil>=0.7.1 15:16:39 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 15:16:39 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 15:16:39 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 15:16:39 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 15:16:39 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 15:16:39 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 15:16:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:16:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:16:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:16:42 INFO - Installing collected packages: psutil 15:16:42 INFO - Running setup.py install for psutil 15:16:42 INFO - building 'psutil._psutil_osx' extension 15:16:42 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 15:16:42 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 15:16:42 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 15:16:42 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 15:16:43 INFO - building 'psutil._psutil_posix' extension 15:16:43 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 15:16:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:16:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:16:43 INFO - ^ 15:16:43 INFO - 1 warning generated. 15:16:43 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 15:16:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:16:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:16:43 INFO - Successfully installed psutil 15:16:43 INFO - Cleaning up... 15:16:43 INFO - Return code: 0 15:16:43 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:43 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e02ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e03c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd840c88e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decbe00>, '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 0x10dd34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec6b20>, '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.c1H1xDDWB1/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.iyamyUbuxl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 15:16:43 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.0.0'] in /builds/slave/test/build 15:16:43 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.0.0 15:16:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c1H1xDDWB1/Render', 15:16:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:43 INFO - 'HOME': '/Users/cltbld', 15:16:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:43 INFO - 'LOGNAME': 'cltbld', 15:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:43 INFO - 'MOZ_NO_REMOTE': '1', 15:16:43 INFO - 'NO_EM_RESTART': '1', 15:16:43 INFO - 'PAGER': '/bin/cat', 15:16:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:43 INFO - 'PWD': '/builds/slave/test', 15:16:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:43 INFO - 'SHELL': '/bin/bash', 15:16:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iyamyUbuxl/Listeners', 15:16:43 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 15:16:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:43 INFO - 'USER': 'cltbld', 15:16:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:43 INFO - 'XPC_FLAGS': '0x0', 15:16:43 INFO - 'XPC_SERVICE_NAME': '0', 15:16:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:43 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:16:43 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 15:16:43 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 15:16:43 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 15:16:43 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 15:16:43 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 15:16:43 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 15:16:46 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:16:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:16:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:16:46 INFO - Installing collected packages: mozsystemmonitor 15:16:46 INFO - Running setup.py install for mozsystemmonitor 15:16:46 INFO - Successfully installed mozsystemmonitor 15:16:46 INFO - Cleaning up... 15:16:46 INFO - Return code: 0 15:16:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:16:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:46 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 0x10e02ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e03c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd840c88e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decbe00>, '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 0x10dd34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec6b20>, '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.c1H1xDDWB1/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.iyamyUbuxl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 15:16:46 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 15:16:46 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 15:16:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c1H1xDDWB1/Render', 15:16:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:46 INFO - 'HOME': '/Users/cltbld', 15:16:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:46 INFO - 'LOGNAME': 'cltbld', 15:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:46 INFO - 'MOZ_NO_REMOTE': '1', 15:16:46 INFO - 'NO_EM_RESTART': '1', 15:16:46 INFO - 'PAGER': '/bin/cat', 15:16:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:46 INFO - 'PWD': '/builds/slave/test', 15:16:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:46 INFO - 'SHELL': '/bin/bash', 15:16:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iyamyUbuxl/Listeners', 15:16:46 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 15:16:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:46 INFO - 'USER': 'cltbld', 15:16:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:46 INFO - 'XPC_FLAGS': '0x0', 15:16:46 INFO - 'XPC_SERVICE_NAME': '0', 15:16:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:46 INFO - Downloading/unpacking blobuploader==1.2.4 15:16:46 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 15:16:46 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 15:16:46 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 15:16:46 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 15:16:46 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 15:16:46 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 15:16:49 INFO - Downloading blobuploader-1.2.4.tar.gz 15:16:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:16:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:16:49 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 15:16:49 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 15:16:49 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 15:16:49 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 15:16:49 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 15:16:49 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 15:16:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:16:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:16:49 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 15:16:49 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 15:16:49 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 15:16:49 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 15:16:49 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 15:16:49 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 15:16:50 INFO - Downloading docopt-0.6.1.tar.gz 15:16:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:16:50 INFO - Installing collected packages: blobuploader, requests, docopt 15:16:50 INFO - Running setup.py install for blobuploader 15:16:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:16:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:16:50 INFO - Running setup.py install for requests 15:16:51 INFO - Running setup.py install for docopt 15:16:51 INFO - Successfully installed blobuploader requests docopt 15:16:51 INFO - Cleaning up... 15:16:51 INFO - Return code: 0 15:16:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:51 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 0x10e02ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e03c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd840c88e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decbe00>, '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 0x10dd34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec6b20>, '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.c1H1xDDWB1/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.iyamyUbuxl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 15:16:51 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 15:16:51 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 15:16:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c1H1xDDWB1/Render', 15:16:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:51 INFO - 'HOME': '/Users/cltbld', 15:16:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:51 INFO - 'LOGNAME': 'cltbld', 15:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:51 INFO - 'MOZ_NO_REMOTE': '1', 15:16:51 INFO - 'NO_EM_RESTART': '1', 15:16:51 INFO - 'PAGER': '/bin/cat', 15:16:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:51 INFO - 'PWD': '/builds/slave/test', 15:16:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:51 INFO - 'SHELL': '/bin/bash', 15:16:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iyamyUbuxl/Listeners', 15:16:51 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 15:16:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:51 INFO - 'USER': 'cltbld', 15:16:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:51 INFO - 'XPC_FLAGS': '0x0', 15:16:51 INFO - 'XPC_SERVICE_NAME': '0', 15:16:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:16:51 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-VwEC2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:16:51 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-4S_0jc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:16:51 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-oGWdQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:16:51 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-84BVrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:16:51 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-gUzBnK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:16:52 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-DJQN09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:16:52 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-FEsmjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:16:52 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-ZgPzLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:16:52 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-pt9VIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:16:52 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-BCGKtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:16:52 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-b0gblJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:16:52 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-dl_CZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:16:52 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-unBKAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:16:52 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-3bTjYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:52 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-UiM24F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:16:53 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-J_7sQT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:16:53 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-tslKm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:16:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:16:53 INFO - Running setup.py install for manifestparser 15:16:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:16:53 INFO - Running setup.py install for mozcrash 15:16:53 INFO - Running setup.py install for mozdebug 15:16:53 INFO - Running setup.py install for mozdevice 15:16:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:16:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:16:53 INFO - Running setup.py install for mozfile 15:16:54 INFO - Running setup.py install for mozhttpd 15:16:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:16:54 INFO - Running setup.py install for mozinfo 15:16:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:16:54 INFO - Running setup.py install for mozInstall 15:16:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:16:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:16:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:16:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:16:54 INFO - Running setup.py install for mozleak 15:16:54 INFO - Running setup.py install for mozlog 15:16:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:16:54 INFO - Running setup.py install for moznetwork 15:16:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:16:54 INFO - Running setup.py install for mozprocess 15:16:55 INFO - Running setup.py install for mozprofile 15:16:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:16:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:16:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:16:55 INFO - Running setup.py install for mozrunner 15:16:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:16:55 INFO - Running setup.py install for mozscreenshot 15:16:55 INFO - Running setup.py install for moztest 15:16:55 INFO - Running setup.py install for mozversion 15:16:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:16:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:16:55 INFO - Cleaning up... 15:16:55 INFO - Return code: 0 15:16:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:16:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:55 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 0x10e02ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e03c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd840c88e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decbe00>, '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 0x10dd34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec6b20>, '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.c1H1xDDWB1/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.iyamyUbuxl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 15:16:55 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 15:16:55 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 15:16:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c1H1xDDWB1/Render', 15:16:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:55 INFO - 'HOME': '/Users/cltbld', 15:16:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:55 INFO - 'LOGNAME': 'cltbld', 15:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:55 INFO - 'MOZ_NO_REMOTE': '1', 15:16:55 INFO - 'NO_EM_RESTART': '1', 15:16:55 INFO - 'PAGER': '/bin/cat', 15:16:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:55 INFO - 'PWD': '/builds/slave/test', 15:16:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:55 INFO - 'SHELL': '/bin/bash', 15:16:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iyamyUbuxl/Listeners', 15:16:55 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 15:16:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:55 INFO - 'USER': 'cltbld', 15:16:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:55 INFO - 'XPC_FLAGS': '0x0', 15:16:55 INFO - 'XPC_SERVICE_NAME': '0', 15:16:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:16:56 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-3ewhts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:16:56 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)) 15:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:16:56 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-Zkt5b_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:16:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 15:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:16:56 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-jfmGpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:16:56 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)) 15:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:16:56 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-3M1_sp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:16:56 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)) 15:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:16:56 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-AdDC3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:16:56 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)) 15:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:16:56 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-frAtzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:16:56 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)) 15:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:16:56 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-vlUzqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:16:56 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)) 15:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:16:56 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-wqyRdp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:16:57 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)) 15:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:16:57 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-18ISTL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:16:57 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)) 15:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:16:57 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-WRjo7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:16:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 15:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:16:57 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-9FzXVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:16:57 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)) 15:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:16:57 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-Y4vgcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:16:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 15:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:16:57 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-uNebRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:16:57 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)) 15:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:16:57 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-aDg4sp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:16:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 15:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:57 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-nwFF47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:57 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)) 15:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:16:57 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-hyzyeV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:16:57 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)) 15:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:16:57 INFO - Running setup.py (path:/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/pip-U3S6rt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:16:57 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)) 15:16:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:16:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:16:57 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)) 15:16:57 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)) 15:16:57 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)) 15:16:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:16:57 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 15:16:57 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 15:16:57 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 15:16:57 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 15:16:57 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 15:16:57 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 15:17:00 INFO - Downloading blessings-1.6.tar.gz 15:17:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:17:00 INFO - Installing collected packages: blessings 15:17:00 INFO - Running setup.py install for blessings 15:17:00 INFO - Successfully installed blessings 15:17:00 INFO - Cleaning up... 15:17:00 INFO - Return code: 0 15:17:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:17:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:17:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:17:01 INFO - Reading from file tmpfile_stdout 15:17:01 INFO - Current package versions: 15:17:01 INFO - blessings == 1.6 15:17:01 INFO - blobuploader == 1.2.4 15:17:01 INFO - docopt == 0.6.1 15:17:01 INFO - manifestparser == 1.1 15:17:01 INFO - mozInstall == 1.12 15:17:01 INFO - mozcrash == 0.17 15:17:01 INFO - mozdebug == 0.1 15:17:01 INFO - mozdevice == 0.48 15:17:01 INFO - mozfile == 1.2 15:17:01 INFO - mozhttpd == 0.7 15:17:01 INFO - mozinfo == 0.9 15:17:01 INFO - mozleak == 0.1 15:17:01 INFO - mozlog == 3.1 15:17:01 INFO - moznetwork == 0.27 15:17:01 INFO - mozprocess == 0.22 15:17:01 INFO - mozprofile == 0.28 15:17:01 INFO - mozrunner == 6.11 15:17:01 INFO - mozscreenshot == 0.1 15:17:01 INFO - mozsystemmonitor == 0.0 15:17:01 INFO - moztest == 0.7 15:17:01 INFO - mozversion == 1.4 15:17:01 INFO - psutil == 3.1.1 15:17:01 INFO - requests == 1.2.3 15:17:01 INFO - wsgiref == 0.1.2 15:17:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:17:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:17:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:01 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 0x10e02ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e03c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd840c88e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decbe00>, '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 0x10dd34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec6b20>, '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.c1H1xDDWB1/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.iyamyUbuxl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 15:17:01 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 15:17:01 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 15:17:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c1H1xDDWB1/Render', 15:17:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:01 INFO - 'HOME': '/Users/cltbld', 15:17:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:01 INFO - 'LOGNAME': 'cltbld', 15:17:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:01 INFO - 'MOZ_NO_REMOTE': '1', 15:17:01 INFO - 'NO_EM_RESTART': '1', 15:17:01 INFO - 'PAGER': '/bin/cat', 15:17:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:01 INFO - 'PWD': '/builds/slave/test', 15:17:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:01 INFO - 'SHELL': '/bin/bash', 15:17:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iyamyUbuxl/Listeners', 15:17:01 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 15:17:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:01 INFO - 'USER': 'cltbld', 15:17:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:01 INFO - 'XPC_FLAGS': '0x0', 15:17:01 INFO - 'XPC_SERVICE_NAME': '0', 15:17:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:01 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)) 15:17:01 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)) 15:17:01 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)) 15:17:01 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)) 15:17:01 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)) 15:17:01 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)) 15:17:01 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)) 15:17:01 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)) 15:17:01 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)) 15:17:01 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)) 15:17:01 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)) 15:17:01 INFO - Cleaning up... 15:17:01 INFO - Return code: 0 15:17:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:17:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:01 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:17:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:01 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 0x10e02ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e03c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd840c88e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decbe00>, '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 0x10dd34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec6b20>, '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.c1H1xDDWB1/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.iyamyUbuxl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 15:17:01 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 15:17:01 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 15:17:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c1H1xDDWB1/Render', 15:17:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:01 INFO - 'HOME': '/Users/cltbld', 15:17:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:01 INFO - 'LOGNAME': 'cltbld', 15:17:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:01 INFO - 'MOZ_NO_REMOTE': '1', 15:17:01 INFO - 'NO_EM_RESTART': '1', 15:17:01 INFO - 'PAGER': '/bin/cat', 15:17:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:01 INFO - 'PWD': '/builds/slave/test', 15:17:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:01 INFO - 'SHELL': '/bin/bash', 15:17:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iyamyUbuxl/Listeners', 15:17:01 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 15:17:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:01 INFO - 'USER': 'cltbld', 15:17:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:01 INFO - 'XPC_FLAGS': '0x0', 15:17:01 INFO - 'XPC_SERVICE_NAME': '0', 15:17:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:01 INFO - Downloading/unpacking jsonschema 15:17:01 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 15:17:01 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 15:17:01 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 15:17:01 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 15:17:01 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 15:17:01 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 15:17:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:17:04 INFO - Downloading/unpacking functools32 (from jsonschema) 15:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:17:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:17:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:17:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:17:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:17:04 INFO - Downloading functools32-3.2.3-2.tar.gz 15:17:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:17:04 INFO - warning: no files found matching '*.txt' 15:17:04 INFO - no previously-included directories found matching 'build' 15:17:04 INFO - no previously-included directories found matching 'dist' 15:17:04 INFO - no previously-included directories found matching '.git*' 15:17:04 INFO - Installing collected packages: jsonschema, functools32 15:17:04 INFO - Running setup.py install for functools32 15:17:04 INFO - warning: no files found matching '*.txt' 15:17:04 INFO - no previously-included directories found matching 'build' 15:17:04 INFO - no previously-included directories found matching 'dist' 15:17:04 INFO - no previously-included directories found matching '.git*' 15:17:04 INFO - Successfully installed jsonschema functools32 15:17:04 INFO - Cleaning up... 15:17:04 INFO - Return code: 0 15:17:04 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:17:04 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:17:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:17:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:17:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:17:05 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e02ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e03c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd840c88e10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decbe00>, '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 0x10dd34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec6b20>, '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.c1H1xDDWB1/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.iyamyUbuxl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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 15:17:05 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', 'flake8'] in /builds/slave/test/build 15:17:05 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 flake8 15:17:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c1H1xDDWB1/Render', 15:17:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:05 INFO - 'HOME': '/Users/cltbld', 15:17:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:05 INFO - 'LOGNAME': 'cltbld', 15:17:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:05 INFO - 'MOZ_NO_REMOTE': '1', 15:17:05 INFO - 'NO_EM_RESTART': '1', 15:17:05 INFO - 'PAGER': '/bin/cat', 15:17:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:05 INFO - 'PWD': '/builds/slave/test', 15:17:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:05 INFO - 'SHELL': '/bin/bash', 15:17:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iyamyUbuxl/Listeners', 15:17:05 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 15:17:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:05 INFO - 'USER': 'cltbld', 15:17:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:05 INFO - 'XPC_FLAGS': '0x0', 15:17:05 INFO - 'XPC_SERVICE_NAME': '0', 15:17:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:17:05 INFO - Downloading/unpacking flake8 15:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:17:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:17:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:17:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:17:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:17:08 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:17:08 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:17:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:17:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:17:08 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:17:08 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:17:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:17:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:17:09 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:17:09 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 15:17:09 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 15:17:09 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 15:17:09 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 15:17:09 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 15:17:09 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 15:17:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:17:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:17:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:17:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:17:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:17:09 INFO - no previously-included directories found matching 'docs/_build' 15:17:09 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:17:09 INFO - Running setup.py install for pep8 15:17:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:17:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:17:09 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:17:09 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:17:09 INFO - no previously-included directories found matching 'docs/_build' 15:17:09 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:17:09 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:17:09 INFO - Cleaning up... 15:17:09 INFO - Return code: 0 15:17:09 INFO - Running post-action listener: _resource_record_post_action 15:17:09 INFO - Running post-action listener: _start_resource_monitoring 15:17:10 INFO - Starting resource monitoring. 15:17:10 INFO - ##### 15:17:10 INFO - ##### Running install step. 15:17:10 INFO - ##### 15:17:10 INFO - Running pre-action listener: _resource_record_pre_action 15:17:10 INFO - Running main action method: install 15:17:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:17:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:17:10 INFO - Reading from file tmpfile_stdout 15:17:10 INFO - Detecting whether we're running mozinstall >=1.0... 15:17:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:17:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:17:10 INFO - Reading from file tmpfile_stdout 15:17:10 INFO - Output received: 15:17:10 INFO - Usage: mozinstall [options] installer 15:17:10 INFO - Options: 15:17:10 INFO - -h, --help show this help message and exit 15:17:10 INFO - -d DEST, --destination=DEST 15:17:10 INFO - Directory to install application into. [default: 15:17:10 INFO - "/builds/slave/test"] 15:17:10 INFO - --app=APP Application being installed. [default: firefox] 15:17:10 INFO - mkdir: /builds/slave/test/build/application 15:17:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:17:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 15:17:41 INFO - Reading from file tmpfile_stdout 15:17:41 INFO - Output received: 15:17:41 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:17:41 INFO - Running post-action listener: _resource_record_post_action 15:17:41 INFO - ##### 15:17:41 INFO - ##### Running run-tests step. 15:17:41 INFO - ##### 15:17:41 INFO - Running pre-action listener: _resource_record_pre_action 15:17:41 INFO - Running main action method: run_tests 15:17:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:17:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:17:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:17:41 INFO - 2016-04-29 15:17:41.836 screenresolution[1644:7533] starting screenresolution argv=screenresolution get 15:17:41 INFO - 2016-04-29 15:17:41.838 screenresolution[1644:7533] Display 0: 1600x1200x32@60 15:17:41 INFO - 2016-04-29 15:17:41.843 screenresolution[1645:7536] starting screenresolution argv=screenresolution list 15:17:41 INFO - Available Modes on Display 0 15:17:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:17:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:17:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:17:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:17:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:17:42 INFO - Intel Iris: 15:17:42 INFO - Chipset Model: Intel Iris 15:17:42 INFO - Type: GPU 15:17:42 INFO - Bus: Built-In 15:17:42 INFO - VRAM (Dynamic, Max): 1536 MB 15:17:42 INFO - Vendor: Intel (0x8086) 15:17:42 INFO - Device ID: 0x0a2e 15:17:42 INFO - Revision ID: 0x0009 15:17:42 INFO - Displays: 15:17:42 INFO - Display: 15:17:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:17:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:17:42 INFO - Display Serial Number: 1600x1200 60 15:17:42 INFO - Main Display: Yes 15:17:42 INFO - Mirror: Off 15:17:42 INFO - Online: Yes 15:17:42 INFO - Rotation: Supported 15:17:42 INFO - Return code: 0 15:17:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:17:42 INFO - Python 2.7.3 15:17:42 INFO - Return code: 0 15:17:42 INFO - grabbing minidump binary from tooltool 15:17: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:17:42 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decbe00>, '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 0x10dd34db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:17:42 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 15:17:42 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 15:17:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:17:42 INFO - Return code: 0 15:17:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:17:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:17:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:17:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:17:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:17:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:17:43 INFO - Return code: 0 15:17:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:17:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0004 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:17:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.c1H1xDDWB1/Render', 15:17:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:17:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:17:43 INFO - 'HOME': '/Users/cltbld', 15:17:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:17:43 INFO - 'LOGNAME': 'cltbld', 15:17:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:17:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:17:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:17:43 INFO - 'MOZ_NO_REMOTE': '1', 15:17:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:17:43 INFO - 'NO_EM_RESTART': '1', 15:17:43 INFO - 'PAGER': '/bin/cat', 15:17:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:17:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:17:43 INFO - 'PWD': '/builds/slave/test', 15:17:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:17:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:17:43 INFO - 'SHELL': '/bin/bash', 15:17:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iyamyUbuxl/Listeners', 15:17:43 INFO - 'TMPDIR': '/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/', 15:17:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:17:43 INFO - 'USER': 'cltbld', 15:17:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:17:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:17:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:17:43 INFO - 'XPC_FLAGS': '0x0', 15:17:43 INFO - 'XPC_SERVICE_NAME': '0', 15:17:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:17:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:17:43 INFO - mozversion application_buildid: 20160429140836 15:17:43 INFO - mozversion application_changeset: 7c17eb91821968d66808c032fb3f38885d0dedda 15:17:43 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:17:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:17:43 INFO - mozversion application_name: Firefox 15:17:43 INFO - mozversion application_remotingname: firefox-dev 15:17:43 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:17:43 INFO - mozversion application_vendor: Mozilla 15:17:43 INFO - mozversion application_version: 48.0a2 15:17:43 INFO - mozversion platform_buildid: 20160429140836 15:17:43 INFO - mozversion platform_changeset: 7c17eb91821968d66808c032fb3f38885d0dedda 15:17:43 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:17:43 INFO - mozversion platform_version: 48.0a2 15:17:43 INFO - using testdate: 1461968263 15:17:43 INFO - actual date: 1461968263 15:17:43 INFO - starting webserver on 'localhost:49275' 15:17:43 INFO - SUITE-START | Running 2 tests 15:17:43 INFO - TEST-START | dromaeo_css 15:17:43 INFO - operating with platform_type : mac_ 15:17:43 INFO - Initialising browser for dromaeo_css test... 15:17:43 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/tmpM2NITn/profile http://localhost:49275/getInfo.html) 15:17:46 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 15:17:46 INFO - PROCESS | 1663 | colorDepth:24 15:17:46 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 15:17:46 INFO - PROCESS | 1663 | __metrics 15:17:46 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 15:17:46 INFO - TEST-INFO | 1663: exit 0 15:17:46 INFO - Browser initialized. 15:17:46 INFO - Running cycle 1/1 for dromaeo_css test... 15:17:46 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/tmpM2NITn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:17:58 INFO - PROCESS | 1666 | RSS: Main: 221536256 15:17:58 INFO - PROCESS | 1666 | 15:18:48 INFO - PROCESS | 1666 | 1461968328590 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] 15:20:59 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 15:21:00 INFO - PROCESS | 1666 | RSS: Main: 231702528 15:21:00 INFO - PROCESS | 1666 | 15:24:02 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 15:24:03 INFO - PROCESS | 1666 | RSS: Main: 243466240 15:24:03 INFO - PROCESS | 1666 | 15:27:05 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 15:27:05 INFO - PROCESS | 1666 | RSS: Main: 245489664 15:27:05 INFO - PROCESS | 1666 | 15:30:07 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 15:30:08 INFO - PROCESS | 1666 | RSS: Main: 247074816 15:30:08 INFO - PROCESS | 1666 | 15:33:10 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 15:33:11 INFO - PROCESS | 1666 | RSS: Main: 250253312 15:33:11 INFO - PROCESS | 1666 | 15:36:13 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 15:36:13 INFO - PROCESS | 1666 | RSS: Main: 251375616 15:36:13 INFO - PROCESS | 1666 | 15:36:13 INFO - PROCESS | 1666 | __start_tp_report 15:36:13 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 15:36:13 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 15:36:13 INFO - PROCESS | 1666 | |i|pagename|runs| 15:36:13 INFO - PROCESS | 1666 | |0;dojo.html;2657.813953023288;3442.7073698735608;3459.308138372326;3486.686198242158;3506.070891213827;5675.943240567593;5680.488756011958;5686.573506986977;5699.943000569993;5702.800401985931;5611.523020543259;5695.943040569593;5701.429857014296;5743.4543718346695;5744.109663002241;5128.358955130618;5146.125158723026;5182.937497812958;5232.973835130819;5264.341957255343;5105.081085404626;5200.583953283747;5231.65994210377;5236.214567814839;5328.094223981913;5175.611829112813;5210.348706411699;5211.869703257411;5211.9576084783;5213.296205012312;5165.81919632813;5203.479652034785;5216.008958297949;5223.764930578133;5225.346831646044;4977.178765503688;4994.575461085812;5093.032323858455;5095.745212739348;5207.4011488678525;4699.78850951708;5514.944850551524;5630.605857589971;5675.886482270332;5680.914786278209;5317.9468205317835;6041.939580604182;6125.78559750411;6203.596766210227;6239.594426362272;6211.34780848009;6275.435210831001;6279.434850863444;6294.276158241815;6294.527910406738;5780.3063632363965;5788.797392091299;5802.622829516068;5822.33043200029;5822.534197264251;6218.751249950042;6234.3453937336835;6274.623522588659;6290.465310448663;6297.748090076438;6087.482563982023;6124;6140.10633425593;6168.568200225941;6186.566940314135;6228.408301211378;6258.874822503524;6274.435300822948;6299.370062993664;6325.683715814191;6053.636781793107;6120.969395153084;6173.9073913891325;6194.845128871814;6208;6208.379162083756;6231.657258850807;6250.562460627803;6277.560570760094;6281.466075383643;4952.356193694796;4958.752062396866;4964.379452568429;4973.1297023020825;4974.129527332702;4257.27626303523;4371.715838470553;4422.646188304928;4432.047109871393;4445.911081778347;5166.121759300928;5395.570173903919;5752.913706294409;5754.5971781975695;5774.68239246837;6265.843353916189;6279.96860015697;6289.496840252768;6314.116023756677;6314.652694101868;5559.16612508127;5679.574031947621;5742.913856292159;5744.540436765049;5761.135829625589;5339.7063161526485;5375.462453754593;5380.757865896044;5380.892382152335;5395.73021348931;6206.410391012847;6329.3354197809485;6335.873282534323;6348.206474190726;6360.950443176824;5816.94183058164;6186.536009799193;6212.099245609271;6262.06069089622;6274.717637706405;6271.184745982993;6313.43179113882;6321.778737744157;6336.651484168415;6337.6197428154455;6049.818505444844;6112.898653789679;6205.844853878689;6208.192934918612;6231.065340199007;6204.472619827366;6242.701029185886;6246.562740608114;6286.465650419766;6311.179546658905;5113.948860511347;5989.640621562546;6093.421124993119;6108.786192483241;6122.357152498924;5894.528437724886;6063.818085457443;6120.938790612215;6139.079138129317;6153.938460615515;5812.912806307993;5913.46778789911;5957.672328022;5982.641041537521;5997.340292567901;6080.604760690541;6125.84685382869;6159.168512250968;6166.044263139221;6167.321594624677;5803.738831752666;5936.762529498772;5962.731677074629;6009.399060093956;6045.516358691206 15:36:13 INFO - PROCESS | 1666 | |1;ext.html;504.9343585333906;539.2935254816191;541.9891602167957;552.2213678713014;557.3395526301335;38775.61224387755;39523.20953580927;39819.80090099553;39996;40029;43484.13031739363;43557.12885742283;44221;44489.55510444895;44509.554904451026;9861.309708320421;9938.850917236246;9982.201423886108;10001.899981000206;10006.849897251546;9750.171235444996;9810.411375317468;9841.852372214387;9908.603655853758;9919.851202231972;9845.458499782544;9856.35933664313;9869.161121304698;9895.208383329353;9973.252006099536;9765.560549775242;9845.90154098457;9874;9875.60497580096;9885.060919212663;9788.95105524475;9904.851427228597;9924.80150396988;9969.90030099697;9980.55087521056;40223.195536089115;40687.38968915468;40922.590774092474;41517.75446736603;41565.58434415647;2803.593478945562;3136.2316232523176;3223.5003574446;3270.787398819093;3317.402867483852;22887.88556057209;27606.585901211496;27737.445251094865;28392.574111388345;28908.710912890812;2738.3701748597737;2804.8317100973804;2809.2415047937147;2849.487092323381;2922.21100302917;484420;489070;490928;493764.53117734543;495716;35033.29933401318;35781;68056;69681.30318696747;69791;36366;36544.903652890454;36571.08572285714;36585;36665.26669466596;15479.84520154784;15657.530274091796;15734.842651573337;15755.4485593006;15768.68462630741;10971.561137554569;11352.375619341015;12883.806742898863;13207.933960330136;13287.269200193889;6887.345702158287;8306.377021723394;8315.625796839156;8411;8457.154284571494;3426.554547908756;3433.3305005524185;3484.1463841359027;3486.4073107571776;3516.331896939573;877.3113106211594;920.8351435434181;979.3878825733916;1075.7310672331917;1150.6433005768238;23417.648735268984;30923.226919327197;31436;31588.894388696284;33124.834375827966;858.121865223216;893.6202114101611;1021.5658345203259;1059.6079450603327;1068.4978060311641;15331.310091045929;15470.5358839235;15573.987690800062;15600;15854.603634908988;679901;681322;685856;690794;691063.5446822734;62296;64783.67608162023;64910.35089649138;64913;64928.35071649317;1296.3712599389478;1493.104137517481;1505.2022428113119;1515.9242037898061;1517.3930427828736;24061.759382406653;26660.733392665825;30484;30782.692173078878;31331.74673013054;46450;57101.42898570961;57211.427885722274;57782.7110864443;57836;541359;588023;604112;607074;608965;27722.722772772013;27994.160175193963;28172.85913570419;28371.290717731397;28436.004739834832;48522.5147748518;48776;49982;50046.99906001933;50966.49033509766;39570.40644390394;40051.79974100111;40392;40749.79625101855;40845;39127.60872391354;39174.41238381484;39491;39912.30904598716;39927.600723992386;32957.34085318328;38233.617663823;39276.2144757109;39298.60701393064;39377.60622393739;31968.68031319657;33127.33745325128;33368;33423.83288083544;33436.99689009334;24210.87894560516;24499.63250551279;24638;24757.50484990254;24931 15:36:13 INFO - PROCESS | 1666 | |2;jquery.html;390.17867389145863;407.8633657724662;413.92963196256636;415.1178745166521;422.5605370414769;14530.34613442395;16112.436064737736;20422;20535.589288214247;20541.691874621894;19876.40370788875;20561.383158505232;23319.650205246933;24095.32612225526;24155;6545.509086818483;6886.552374095692;6988.965055174717;6989.231184569693;6990.790276291719;5613.326400831886;6530.346965303455;6753.594784312957;6943.270956549565;6952.826179345506;5978.701064946757;6852.5203235773515;6939.37545620894;6943.652817359137;6952.2700116487795;6687.866242675168;6900.827479313007;6920.377166055054;6964.965175174092;7001.334873187039;6505.902411463831;6759.797206083826;6813.11429514165;6814.659267036628;6839.1451068616425;28545.143645690456;29517.557236641253;30399.24001899948;30573.694263057307;30929.072127835974;1752.6319472910704;2048.5185981294385;2297.379707478988;2345.179187284437;2438.9634155487684;12536.310502922335;15528.930624075547;16086.919565402099;17073.40243091486;17181.711371647052;1802.97295540567;1840.7146892231726;1854.360245715226;1923.903804809768;1961.3135402609116;61535.692321538554;99737;104840;119538;122606.77393226043;21818.581792183457;22535.88732056362;22576.887115564317;22718.09127634876;22763.634181948804;36953.630463695554;37055;37595.62404375976;37609.87170384893;37735.05662358463;11400.600978965696;11472.31166130035;11650.242734222249;11657.125715571367;11711.648650540497;9427.292953028551;9522.666706665234;9524.476153811607;9546.713598592054;9586.568604412818;10261;10357.585696572052;10433.373997560171;10456.424896630606;10479.10927571165;3878.166194268246;3941.2117576484816;3981.5819338969573;3981.7212795104065;4086.3461846105074;827.2017503109408;916.276141847946;946.4605175050293;991.945443000642;992.2409356842022;19926.003699815243;20943.581128377347;20954.685679714818;21106.78893211079;21184.258550950643;951.8762560867043;954.9236061115233;958.712386284112;967.0909345215475;980.5881529757519;10736;10964.013238808546;11000;11016.449177541092;11044.447777611247;513710;632311;637139;637194;640033;112494;114510.70978580382;133952.33023835014;135269;141373.29313353368;837.874318852177;1012.4128005756644;1076.6554702495127;1091.8689757229024;1092.355510248957;18171;18639;19185.10335492973;19566.510837229183;19655;55285;55319.89360212853;55781.884362313336;56270.264616671964;56326.8734625297;81034;81228.1570145042;82691.45197014223;82851;83017.58491207747;7320.487565870338;7403.5928023959195;8392.4544904581;8425.452345597516;8484.745457636282;12316.815247771112;12732.490700371882;12888.548900788803;12937.741245175232;13124.278164701032;10985.945070274598;11254.662360129209;11514.366709830705;11552.94223528877;11616.954474097372;8616.095309992612;10254.769427668727;10784.622538211124;11031.117510599293;11392.601258955896;8857.64569417242;10835.620753273597;11795.351255680676;11978.64064078079;12027.579034733742;7924;8203.8104474852;8410.453320534145;8692.652293908173;8793.20861122502;9399.718008459757;9652.710418687451;11317.603883864025;11537.192396532162;11612.593559225386 15:36:13 INFO - PROCESS | 1666 | |3;mootools.html;1588.3329001819236;1614.1445034131907;1886.6698327792633;1975.2099160335872;1986.324649619131;7865.449418540705;8578.39951203416;8755.824883502342;9086.681966131187;9088.500132492718;8773;8854.557272136399;8875.91124088759;8879.511626860536;8922.107789221074;2454.5336386086587;2486.0925762096886;2489.5892177790643;2500.1499490173337;2505.210858579551;2433.2335314375914;2493.463905260369;2497.3631723910376;2498.2380373985925;2520.8991640334457;2457.496213276274;2465.987670061656;2510.309664842174;2512.371907023244;2512.798976081918;2470.17249221511;2471.938201544967;2477.9008839646394;2491.4145175883655;2492.264781889347;2422.4065104049446;2434.257551446808;2441.023590563771;2441.816863735209;2482.925512234636;9032.09679032098;9088.045755195675;9098.044705305914;9154.450732956044;9208.309376796768;5320.281761962163;5482.205080263379;5523.447655234444;5552.08390615548;5555.861103472405;3620.2035552178477;3651.8174091295596;3657.9451308230396;3706.1105334719473;3709.4250391189407;1021.5556233038652;1045.2578669144916;1047.1256500821823;1067.6796960911695;1100.3178029621606;9977.451240181786;11658.183927125105;11837.171398002143;11843.822342664867;11891.762164756743;4489.910201795946;4821.95178048215;4887.193613053877;4948.480409557017;4993.976234871842;9430.575624096931;10035.849462258071;10108.696739097839;10137.594496220068;10180.134688551409;5420.783168673209;5469.753861076261;5519.834404967857;5524.861878453071;5545.805896793593;4497.190505708938;4888.388951381077;4972.925406118911;5054.494550544916;5066.290719299301;3164.683531646817;4056.411820286042;4120.456249753342;4160.313548264563;4180.393842892824;1371.081375478432;1371.8285214348207;1372.9244891530861;1373.8557451467652;1386.9736395067578;910.2945217456391;930.734740598926;932.379967321737;934.7008957133788;935.8596210568469;5778.479936805707;5785.0454674978155;5826.863761566538;5878.235829342244;5895.882082358329;457.83517933543897;471.9764011799396;472.92433210686124;477.2316570321779;481.0163216222763;3317.668233176663;3697.205100903319;3847.3459511883048;3884.6892248620043;3925.4700615416555;10572.788544229072;10985;11001.449927503752;11081.889181108085;11092.168087393316;10344.37933723979;10902.182336324808;10959.890401096047;10978.011978921935;10997.285176463663;8255.793605159919;8351.916480835236;8366.958165209257;8371.079181289932;8391.790205244917;5597.076482380525;5628.690422026828;5632.605717599728;5684.573656975826;5690.089585666439;10024.699259022242;10080.949595251977;10116.494175291353;10135.037171468699;10193.337433066827;9856.408615483095;10078.748031299276;10252.794944101226;10471.162307015267;10489.842652360068;5329.893402131858;6718.764843230463;6850.0409942607075;7201.459890508128;7586.355159811478;8361.456505327134;8394.538300393537;8436;8437.240648341678;8445.746627601182;7389.334959853639;7597.3162415382885;7666;7670.850076054561;7823.804404889924;6902.551334163464;7220.3140701633265;7509.812254693677;7574.545527268381;7617.6572054256585;6902.896456553256;6953.096097507291;6968.5122041458635;7005.019297298484;7035.613041282779;4748.09786140646;4777.522247775195;4856.781444835062;4857.344258525054;4878.414590249193;4878.536539028786;4883.658143929891;4902.926456103232;4998.500149985118;5081.796728130834 15:36:13 INFO - PROCESS | 1666 | |4;prototype.html;306.9631644202696;308.1602632825551;309.6578280999497;315.85312829534263;317.5474948198819;5202.791888324463;5483.38096856075;5529.751161197751;5530.760042182912;5578.972105139474;5329.546988505972;5415.7002319443245;5437.13005919053;5454.863628409292;5460.34475862896;3382.7293816494685;3398.4392575225056;4179.686523510733;4192.371144328345;4195.2029114468305;3926.802325290785;3951.5850835662272;4137.234611596849;4176.227397931377;4192.790360481979;3783.451399547064;4157.251694694954;4166.666666666674;4171.645410140141;4188.806190235783;3628.219932714466;3642.9271414571826;3919.862804801818;4168.7290326128705;4171.91656166875;3264.4287249731437;3507.473878918157;3528.629493903129;3562.2697347043795;3604.621514740967;4757.286407038937;4896.951030489685;5320.707361095138;5357.249985002064;5408.702521361323;3825.674817640504;4012.378081397359;4465.82136714531;4755.738434386108;4813.229883218668;5634.2389133040515;5822.359540450547;5829.708514574297;5844.620099693507;5864.736086876101;3307.570015897918;3850.152966347399;3956.990967303348;3957.3074711925296;3961.187956468917;5620.5503559715535;5624.55003599711;5790.7973220937065;5832.154337621064;6122.969385153046;3963.36586146221;3997.6601988830694;4005.178938317638;4011.7592944423427;4020.7386519876113;3109.1395915757557;3350.4304268273963;4048.2713111640187;4068.532118806316;4138.089620283533;4611.815527378934;4704.200285951397;4773.64197685175;4791.161546729378;4809.422869255712;4741.644376671763;5878.970605146947;5886.323072846678;5902.4097590240635;5924.7926322578505;1223.7797196504537;1462.5612316305067;1477.1683112939877;1480.000999325451;1480.289461058698;892.5715656484923;944.2964991081689;948.9699380225121;955.4267439536222;970.3401686853008;5740.885182296331;5757.193992841006;5781.277340332458;5782.826515204551;5810.6513609183585;6128.233970753656;6337.588056776295;6508.414242718178;6538.574992625464;6554.311797261219;5495.818399044225;5496.560275177976;5743.4256574342235;5782.421757824268;5784.913226301609;2539.7460253974823;3401.1837159081642;3410.6077801053198;3412.2663627320244;3413.4709120086427;5350.812721554805;6392.872142557123;6783.219929708048;6796.796096117125;6801.727930882808;5530.557555395559;6246.875062498815;6267.874642507216;6286.245650521967;6290.339514351019;6235.750569977332;6384.691138316634;6410.884408145301;6415.743370265137;6430.839229019219;5884.47039766423;6068.9089663654195;6085.391460854056;6106.664133472702;6123.326434092335;4885.438174609965;5054.458390191027;5977.193078934288;6028.758849645965;6117.755289788359;5599.99200143978;6007.699615019319;6285.622862628257;6327.240731112296;6375.776847810304;4078.0416602099076;4153.543110257809;4180.888651231109;4181.912702697381;4183.477065366829;5420.430854760193;5677.694130350125;6071.423214794588;6075.939240607538;6091.238595175603;5038.269450929522;5265.6050796189675;5601.80393686219;5611.933732590958;5766.4233576642;4713.9057218856115;4753.786079626494;5319.973400132974;5812.041013232938;5833.970830145992;4827.517248275144;5494.560435165097;5629.6903670298525;5658.632188907708;5695.743691533974;4819.566239038503;5115.718635475085;5173.3533308336455;5792.768289268382;5888.998870191992;4790.856274311776;5080.7205603692155;5326.627136100436;5773.6824474654295;5809.506191973561 15:36:13 INFO - PROCESS | 1666 | |5;yui.html;662.2913482573645;699.765578531192;740.5741698523348;744.255744255744;754.0837881973401;13399.665008374783;15001.474948376808;15158.848411515895;15216.467423640175;15224.23878806058;13847;13912.860871391309;13916.721665566683;13947.790783138236;13949.860501394984;723.4465633790129;912.5072460871122;916.3127654259306;950.0452045694068;953.6709835106911;938.2540880000416;939.0421769794805;948.7770373962113;952.3238500664502;952.5618215620788;944.2964991081653;944.7260294514582;991.9553620087078;995.7809281957958;1006.2754816532085;952.4904176265665;971.9562619682131;973.6154219083458;999.715081201854;1001.284081881451;711.2460791560962;741.4772585327386;747.7943565519471;748.0798617700259;752.1575835064708;11981.880181198163;13477.921766258676;13493.86506134936;14723.263836808117;15165.772513412308;10955.616553420592;12602.558910438182;12636.620901372882;12865.052871449407;12871.935640321739;8102.513849169069;9886.901130988743;10073.05612288206;10210.081092701692;10260.333078349884;3989.7606143631183;4031.9798401007806;4032.254033003874;4147.81334839933;4151.958480415217;76926.61536692336;81724.59137704333;81765.18234817636;84383.57808210982;84601.30797384017;9258.722238332726;9325.953370233105;9371.906280937103;9434.19809316202;9591.616335346647;20562.588748224953;21210.469738256666;21283.78716212849;21331.573368532543;21403.46491337729;7404.703811847466;7436.888446673304;7574.7032744188955;7826;7844.058712954482;3534.876279330211;4099.959000410017;4117.547069822317;4119.711620186558;4128.896777580585;3532.717382609385;3673.246984368198;3683.8158092095287;3684.894531640497;3789.3558095123894;702.2485940044104;710.1903829634222;713.6895450478954;718.052171134098;718.7340683946868;496.8757810547363;520.3261776000089;572.3735678282555;574.6121368076532;584.9795257165979;7769.689212431439;8035.517868927883;8108.391870609727;8266.545340006358;8377.70678026266;561.205893660474;561.9409961954018;573.9368669446358;585.9628457630056;589.628534023571;3718.7768733876055;3817.522809648794;3818.1981783825154;3912.5109361329833;3937.783421911765;105167;108155.45922270339;108604;109058.45470772595;109090;87162.1283787154;87742.56128719315;89193.55403222943;90530.54734726284;90986;77217;77474;78003;78714.21285787184;79029.60485197537;7392.482526223328;7547.50941188821;7583.27958843909;7587.089549254125;7751.293284819504;36169.81915090408;47214.52785472101;47407.28889066598;47788;48003;49289;63482.68258658677;64221.03668444884;67311.31721706997;69777.30222697707;9991.350562213433;10200.59197632116;10276.126529244799;10385.324953877975;10435.113015393777;9146.588403521724;11112.777744445228;11169.218154729091;11183.944080279547;11757.529698812294;10150.441725705157;10156.695299141038;10162;10180.69457916234;10382.688519344432;8759.299256059372;10387.792244155225;10412.219083568914;10524.263301569123;10524.631637892637;9799.951000245239;9920.454375009444;9938.204943604349;10035.09684128432;10088.394696318244;9035.774105647411;9127.178553930178;9181.862272065791;9201.30990175726;9224.123708247706;7510.8497830042;8655.52394618302;8669.696560620348;8803.207711306013;8994.820103598022 15:36:13 INFO - PROCESS | 1666 | __end_tp_report 15:36:13 INFO - PROCESS | 1666 | __start_cc_report 15:36:13 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,418 15:36:13 INFO - PROCESS | 1666 | __end_cc_report 15:36:13 INFO - PROCESS | 1666 | __startTimestamp1461969373874__endTimestamp 15:36:13 INFO - PROCESS | 1666 | 15:36:13 INFO - PROCESS | 1666 | ------- Summary: start ------- 15:36:13 INFO - PROCESS | 1666 | Number of tests: 6 15:36:13 INFO - PROCESS | 1666 | 15:36:13 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5716.63 Median:5936.76 stddev:660.16 (11.1%) stddev-sans-first:617.04 15:36:13 INFO - PROCESS | 1666 | Values: 2657.8 3442.7 3459.3 3486.7 3506.1 5675.9 5680.5 5686.6 5699.9 5702.8 5611.5 5695.9 5701.4 5743.5 5744.1 5128.4 5146.1 5182.9 5233.0 5264.3 5105.1 5200.6 5231.7 5236.2 5328.1 5175.6 5210.3 5211.9 5212.0 5213.3 5165.8 5203.5 5216.0 5223.8 5225.3 4977.2 4994.6 5093.0 5095.7 5207.4 4699.8 5514.9 5630.6 5675.9 5680.9 5317.9 6041.9 6125.8 6203.6 6239.6 6211.3 6275.4 6279.4 6294.3 6294.5 5780.3 5788.8 5802.6 5822.3 5822.5 6218.8 6234.3 6274.6 6290.5 6297.7 6087.5 6124.0 6140.1 6168.6 6186.6 6228.4 6258.9 6274.4 6299.4 6325.7 6053.6 6121.0 6173.9 6194.8 6208.0 6208.4 6231.7 6250.6 6277.6 6281.5 4952.4 4958.8 4964.4 4973.1 4974.1 4257.3 4371.7 4422.6 4432.0 4445.9 5166.1 5395.6 5752.9 5754.6 5774.7 6265.8 6280.0 6289.5 6314.1 6314.7 5559.2 5679.6 5742.9 5744.5 5761.1 5339.7 5375.5 5380.8 5380.9 5395.7 6206.4 6329.3 6335.9 6348.2 6361.0 5816.9 6186.5 6212.1 6262.1 6274.7 6271.2 6313.4 6321.8 6336.7 6337.6 6049.8 6112.9 6205.8 6208.2 6231.1 6204.5 6242.7 6246.6 6286.5 6311.2 5113.9 5989.6 6093.4 6108.8 6122.4 5894.5 6063.8 6120.9 6139.1 6153.9 5812.9 5913.5 5957.7 5982.6 5997.3 6080.6 6125.8 6159.2 6166.0 6167.3 5803.7 5936.8 5962.7 6009.4 6045.5 15:36:13 INFO - PROCESS | 1666 | 15:36:13 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:71015.53 Median:39866.05 stddev:159319.81 (399.6%) stddev-sans-first:159678.78 15:36:13 INFO - PROCESS | 1666 | Values: 504.9 539.3 542.0 552.2 557.3 38775.6 39523.2 39819.8 39996.0 40029.0 43484.1 43557.1 44221.0 44489.6 44509.6 9861.3 9938.9 9982.2 10001.9 10006.8 9750.2 9810.4 9841.9 9908.6 9919.9 9845.5 9856.4 9869.2 9895.2 9973.3 9765.6 9845.9 9874.0 9875.6 9885.1 9789.0 9904.9 9924.8 9969.9 9980.6 40223.2 40687.4 40922.6 41517.8 41565.6 2803.6 3136.2 3223.5 3270.8 3317.4 22887.9 27606.6 27737.4 28392.6 28908.7 2738.4 2804.8 2809.2 2849.5 2922.2 484420.0 489070.0 490928.0 493764.5 495716.0 35033.3 35781.0 68056.0 69681.3 69791.0 36366.0 36544.9 36571.1 36585.0 36665.3 15479.8 15657.5 15734.8 15755.4 15768.7 10971.6 11352.4 12883.8 13207.9 13287.3 6887.3 8306.4 8315.6 8411.0 8457.2 3426.6 3433.3 3484.1 3486.4 3516.3 877.3 920.8 979.4 1075.7 1150.6 23417.6 30923.2 31436.0 31588.9 33124.8 858.1 893.6 1021.6 1059.6 1068.5 15331.3 15470.5 15574.0 15600.0 15854.6 679901.0 681322.0 685856.0 690794.0 691063.5 62296.0 64783.7 64910.4 64913.0 64928.4 1296.4 1493.1 1505.2 1515.9 1517.4 24061.8 26660.7 30484.0 30782.7 31331.7 46450.0 57101.4 57211.4 57782.7 57836.0 541359.0 588023.0 604112.0 607074.0 608965.0 27722.7 27994.2 28172.9 28371.3 28436.0 48522.5 48776.0 49982.0 50047.0 50966.5 39570.4 40051.8 40392.0 40749.8 40845.0 39127.6 39174.4 39491.0 39912.3 39927.6 32957.3 38233.6 39276.2 39298.6 39377.6 31968.7 33127.3 33368.0 33423.8 33437.0 24210.9 24499.6 24638.0 24757.5 24931.0 15:36:13 INFO - PROCESS | 1666 | 15:36:13 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:36830.20 Median:30486.47 stddev:101867.11 (334.1%) stddev-sans-first:102116.13 15:36:13 INFO - PROCESS | 1666 | Values: 390.2 407.9 413.9 415.1 422.6 14530.3 16112.4 20422.0 20535.6 20541.7 19876.4 20561.4 23319.7 24095.3 24155.0 6545.5 6886.6 6989.0 6989.2 6990.8 5613.3 6530.3 6753.6 6943.3 6952.8 5978.7 6852.5 6939.4 6943.7 6952.3 6687.9 6900.8 6920.4 6965.0 7001.3 6505.9 6759.8 6813.1 6814.7 6839.1 28545.1 29517.6 30399.2 30573.7 30929.1 1752.6 2048.5 2297.4 2345.2 2439.0 12536.3 15528.9 16086.9 17073.4 17181.7 1803.0 1840.7 1854.4 1923.9 1961.3 61535.7 99737.0 104840.0 119538.0 122606.8 21818.6 22535.9 22576.9 22718.1 22763.6 36953.6 37055.0 37595.6 37609.9 37735.1 11400.6 11472.3 11650.2 11657.1 11711.6 9427.3 9522.7 9524.5 9546.7 9586.6 10261.0 10357.6 10433.4 10456.4 10479.1 3878.2 3941.2 3981.6 3981.7 4086.3 827.2 916.3 946.5 991.9 992.2 19926.0 20943.6 20954.7 21106.8 21184.3 951.9 954.9 958.7 967.1 980.6 10736.0 10964.0 11000.0 11016.4 11044.4 513710.0 632311.0 637139.0 637194.0 640033.0 112494.0 114510.7 133952.3 135269.0 141373.3 837.9 1012.4 1076.7 1091.9 1092.4 18171.0 18639.0 19185.1 19566.5 19655.0 55285.0 55319.9 55781.9 56270.3 56326.9 81034.0 81228.2 82691.5 82851.0 83017.6 7320.5 7403.6 8392.5 8425.5 8484.7 12316.8 12732.5 12888.5 12937.7 13124.3 10985.9 11254.7 11514.4 11552.9 11617.0 8616.1 10254.8 10784.6 11031.1 11392.6 8857.6 10835.6 11795.4 11978.6 12027.6 7924.0 8203.8 8410.5 8692.7 8793.2 9399.7 9652.7 11317.6 11537.2 11612.6 15:36:13 INFO - PROCESS | 1666 | 15:36:13 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5749.93 Median:2651.48 stddev:3225.57 (121.7%) stddev-sans-first:3219.46 15:36:13 INFO - PROCESS | 1666 | Values: 1588.3 1614.1 1886.7 1975.2 1986.3 7865.4 8578.4 8755.8 9086.7 9088.5 8773.0 8854.6 8875.9 8879.5 8922.1 2454.5 2486.1 2489.6 2500.1 2505.2 2433.2 2493.5 2497.4 2498.2 2520.9 2457.5 2466.0 2510.3 2512.4 2512.8 2470.2 2471.9 2477.9 2491.4 2492.3 2422.4 2434.3 2441.0 2441.8 2482.9 9032.1 9088.0 9098.0 9154.5 9208.3 5320.3 5482.2 5523.4 5552.1 5555.9 3620.2 3651.8 3657.9 3706.1 3709.4 1021.6 1045.3 1047.1 1067.7 1100.3 9977.5 11658.2 11837.2 11843.8 11891.8 4489.9 4822.0 4887.2 4948.5 4994.0 9430.6 10035.8 10108.7 10137.6 10180.1 5420.8 5469.8 5519.8 5524.9 5545.8 4497.2 4888.4 4972.9 5054.5 5066.3 3164.7 4056.4 4120.5 4160.3 4180.4 1371.1 1371.8 1372.9 1373.9 1387.0 910.3 930.7 932.4 934.7 935.9 5778.5 5785.0 5826.9 5878.2 5895.9 457.8 472.0 472.9 477.2 481.0 3317.7 3697.2 3847.3 3884.7 3925.5 10572.8 10985.0 11001.4 11081.9 11092.2 10344.4 10902.2 10959.9 10978.0 10997.3 8255.8 8351.9 8367.0 8371.1 8391.8 5597.1 5628.7 5632.6 5684.6 5690.1 10024.7 10080.9 10116.5 10135.0 10193.3 9856.4 10078.7 10252.8 10471.2 10489.8 5329.9 6718.8 6850.0 7201.5 7586.4 8361.5 8394.5 8436.0 8437.2 8445.7 7389.3 7597.3 7666.0 7670.9 7823.8 6902.6 7220.3 7509.8 7574.5 7617.7 6902.9 6953.1 6968.5 7005.0 7035.6 4748.1 4777.5 4856.8 4857.3 4878.4 4878.5 4883.7 4902.9 4998.5 5081.8 15:36:13 INFO - PROCESS | 1666 | 15:36:13 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4744.85 Median:5354.03 stddev:1504.04 (28.1%) stddev-sans-first:1470.91 15:36:13 INFO - PROCESS | 1666 | Values: 307.0 308.2 309.7 315.9 317.5 5202.8 5483.4 5529.8 5530.8 5579.0 5329.5 5415.7 5437.1 5454.9 5460.3 3382.7 3398.4 4179.7 4192.4 4195.2 3926.8 3951.6 4137.2 4176.2 4192.8 3783.5 4157.3 4166.7 4171.6 4188.8 3628.2 3642.9 3919.9 4168.7 4171.9 3264.4 3507.5 3528.6 3562.3 3604.6 4757.3 4897.0 5320.7 5357.2 5408.7 3825.7 4012.4 4465.8 4755.7 4813.2 5634.2 5822.4 5829.7 5844.6 5864.7 3307.6 3850.2 3957.0 3957.3 3961.2 5620.6 5624.6 5790.8 5832.2 6123.0 3963.4 3997.7 4005.2 4011.8 4020.7 3109.1 3350.4 4048.3 4068.5 4138.1 4611.8 4704.2 4773.6 4791.2 4809.4 4741.6 5879.0 5886.3 5902.4 5924.8 1223.8 1462.6 1477.2 1480.0 1480.3 892.6 944.3 949.0 955.4 970.3 5740.9 5757.2 5781.3 5782.8 5810.7 6128.2 6337.6 6508.4 6538.6 6554.3 5495.8 5496.6 5743.4 5782.4 5784.9 2539.7 3401.2 3410.6 3412.3 3413.5 5350.8 6392.9 6783.2 6796.8 6801.7 5530.6 6246.9 6267.9 6286.2 6290.3 6235.8 6384.7 6410.9 6415.7 6430.8 5884.5 6068.9 6085.4 6106.7 6123.3 4885.4 5054.5 5977.2 6028.8 6117.8 5600.0 6007.7 6285.6 6327.2 6375.8 4078.0 4153.5 4180.9 4181.9 4183.5 5420.4 5677.7 6071.4 6075.9 6091.2 5038.3 5265.6 5601.8 5611.9 5766.4 4713.9 4753.8 5320.0 5812.0 5834.0 4827.5 5494.6 5629.7 5658.6 5695.7 4819.6 5115.7 5173.4 5792.8 5889.0 4790.9 5080.7 5326.6 5773.7 5809.5 15:36:13 INFO - PROCESS | 1666 | 15:36:13 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:18762.33 Median:63851.86 stddev:28039.89 (43.9%) stddev-sans-first:28085.61 15:36:13 INFO - PROCESS | 1666 | Values: 662.3 699.8 740.6 744.3 754.1 13399.7 15001.5 15158.8 15216.5 15224.2 13847.0 13912.9 13916.7 13947.8 13949.9 723.4 912.5 916.3 950.0 953.7 938.3 939.0 948.8 952.3 952.6 944.3 944.7 992.0 995.8 1006.3 952.5 972.0 973.6 999.7 1001.3 711.2 741.5 747.8 748.1 752.2 11981.9 13477.9 13493.9 14723.3 15165.8 10955.6 12602.6 12636.6 12865.1 12871.9 8102.5 9886.9 10073.1 10210.1 10260.3 3989.8 4032.0 4032.3 4147.8 4152.0 76926.6 81724.6 81765.2 84383.6 84601.3 9258.7 9326.0 9371.9 9434.2 9591.6 20562.6 21210.5 21283.8 21331.6 21403.5 7404.7 7436.9 7574.7 7826.0 7844.1 3534.9 4100.0 4117.5 4119.7 4128.9 3532.7 3673.2 3683.8 3684.9 3789.4 702.2 710.2 713.7 718.1 718.7 496.9 520.3 572.4 574.6 585.0 7769.7 8035.5 8108.4 8266.5 8377.7 561.2 561.9 573.9 586.0 589.6 3718.8 3817.5 3818.2 3912.5 3937.8 105167.0 108155.5 108604.0 109058.5 109090.0 87162.1 87742.6 89193.6 90530.5 90986.0 77217.0 77474.0 78003.0 78714.2 79029.6 7392.5 7547.5 7583.3 7587.1 7751.3 36169.8 47214.5 47407.3 47788.0 48003.0 49289.0 63482.7 64221.0 67311.3 69777.3 9991.4 10200.6 10276.1 10385.3 10435.1 9146.6 11112.8 11169.2 11183.9 11757.5 10150.4 10156.7 10162.0 10180.7 10382.7 8759.3 10387.8 10412.2 10524.3 10524.6 9800.0 9920.5 9938.2 10035.1 10088.4 9035.8 9127.2 9181.9 9201.3 9224.1 7510.8 8655.5 8669.7 8803.2 8994.8 15:36:13 INFO - PROCESS | 1666 | -------- Summary: end -------- 15:36:13 INFO - PROCESS | 1666 | 15:36:14 INFO - TEST-INFO | 1666: exit 0 15:36:14 INFO - TEST-OK | dromaeo_css | took 1110894ms 15:36:14 INFO - TEST-START | kraken 15:36:14 INFO - operating with platform_type : mac_ 15:36:14 INFO - Initialising browser for kraken test... 15:36:14 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/tmp7lCRBI/profile http://localhost:49275/getInfo.html) 15:36:17 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 15:36:17 INFO - PROCESS | 1679 | colorDepth:24 15:36:17 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 15:36:17 INFO - PROCESS | 1679 | __metrics 15:36:17 ERROR - PROCESS | 1679 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 15:36:17 INFO - TEST-INFO | 1679: exit 0 15:36:17 INFO - Browser initialized. 15:36:17 INFO - Running cycle 1/1 for kraken test... 15:36:17 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/10/1x852mss3jl9sjlsx99svtn400000w/T/tmp7lCRBI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:36:29 INFO - PROCESS | 1682 | RSS: Main: 221765632 15:36:29 INFO - PROCESS | 1682 | 15:36:32 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 15:36:33 INFO - PROCESS | 1682 | RSS: Main: 233537536 15:36:33 INFO - PROCESS | 1682 | 15:36:36 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 15:36:37 INFO - PROCESS | 1682 | RSS: Main: 242962432 15:36:37 INFO - PROCESS | 1682 | 15:36:40 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 15:36:40 INFO - PROCESS | 1682 | RSS: Main: 246276096 15:36:40 INFO - PROCESS | 1682 | 15:36:42 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 15:36:43 INFO - PROCESS | 1682 | RSS: Main: 246312960 15:36:43 INFO - PROCESS | 1682 | 15:36:45 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:36:46 INFO - PROCESS | 1682 | RSS: Main: 247824384 15:36:46 INFO - PROCESS | 1682 | 15:36:48 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 15:36:49 INFO - PROCESS | 1682 | RSS: Main: 248844288 15:36:49 INFO - PROCESS | 1682 | 15:36:52 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 15:36:53 INFO - PROCESS | 1682 | RSS: Main: 248774656 15:36:53 INFO - PROCESS | 1682 | 15:36:55 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 15:36:56 INFO - PROCESS | 1682 | RSS: Main: 248864768 15:36:56 INFO - PROCESS | 1682 | 15:36:58 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:36:58 INFO - PROCESS | 1682 | RSS: Main: 248004608 15:36:58 INFO - PROCESS | 1682 | 15:37:00 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:37:01 INFO - PROCESS | 1682 | RSS: Main: 248004608 15:37:01 INFO - PROCESS | 1682 | 15:37:03 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:37:04 INFO - PROCESS | 1682 | RSS: Main: 248188928 15:37:04 INFO - PROCESS | 1682 | 15:37:07 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:37:07 INFO - PROCESS | 1682 | RSS: Main: 248438784 15:37:07 INFO - PROCESS | 1682 | 15:37:10 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:37:11 INFO - PROCESS | 1682 | RSS: Main: 248430592 15:37:11 INFO - PROCESS | 1682 | 15:37:13 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 15:37:13 INFO - PROCESS | 1682 | RSS: Main: 250064896 15:37:13 INFO - PROCESS | 1682 | 15:37:13 INFO - PROCESS | 1682 | __start_tp_report 15:37:13 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 15:37:13 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 15:37:13 INFO - PROCESS | 1682 | |i|pagename|runs| 15:37:13 INFO - PROCESS | 1682 | |0;ai-astar;119;126;172;122;122;133;126;105;148;169 15:37:13 INFO - PROCESS | 1682 | |1;audio-beat-detection;155;130;198;157;123;134;144;184;180;121 15:37:13 INFO - PROCESS | 1682 | |2;audio-dft;194;194;199;197;193;193;220;193;197;197 15:37:13 INFO - PROCESS | 1682 | |3;audio-fft;70;69;80;145;55;56;62;66;114;51 15:37:13 INFO - PROCESS | 1682 | |4;audio-oscillator;81;84;125;76;78;78;117;82;79;116 15:37:13 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;87;86;88;86;88;85;88;89;88;85 15:37:13 INFO - PROCESS | 1682 | |6;imaging-darkroom;97;152;104;151;138;94;115;120;94;94 15:37:13 INFO - PROCESS | 1682 | |7;imaging-desaturate;90;91;91;89;92;92;91;94;90;91 15:37:13 INFO - PROCESS | 1682 | |8;json-parse-financial;61;64;67;66;67;66;62;81;63;90 15:37:13 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;63;65;65;67;77;74;77;60;93;93 15:37:13 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;82;78;83;83;95;107;109;76;82;87 15:37:13 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;126;149;160;137;150;151;157;133;174;133 15:37:13 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;171;172;170;195;160;222;224;178;169;167 15:37:13 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;70;69;72;69;96;68;66;71;69;68 15:37:13 INFO - PROCESS | 1682 | __end_tp_report 15:37:13 INFO - PROCESS | 1682 | __start_cc_report 15:37:13 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,1096 15:37:13 INFO - PROCESS | 1682 | __end_cc_report 15:37:13 INFO - PROCESS | 1682 | __startTimestamp1461969433327__endTimestamp 15:37:13 INFO - PROCESS | 1682 | 15:37:13 INFO - PROCESS | 1682 | ------- Summary: start ------- 15:37:13 INFO - PROCESS | 1682 | Number of tests: 14 15:37:13 INFO - PROCESS | 1682 | 15:37:13 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:134.20 Median:129.50 stddev:21.98 (17.0%) stddev-sans-first:22.61 15:37:13 INFO - PROCESS | 1682 | Values: 119.0 126.0 172.0 122.0 122.0 133.0 126.0 105.0 148.0 169.0 15:37:13 INFO - PROCESS | 1682 | 15:37:13 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:152.60 Median:156.00 stddev:27.14 (17.4%) stddev-sans-first:28.77 15:37:13 INFO - PROCESS | 1682 | Values: 155.0 130.0 198.0 157.0 123.0 134.0 144.0 184.0 180.0 121.0 15:37:13 INFO - PROCESS | 1682 | 15:37:13 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:197.70 Median:197.00 stddev:8.12 (4.1%) stddev-sans-first:8.51 15:37:13 INFO - PROCESS | 1682 | Values: 194.0 194.0 199.0 197.0 193.0 193.0 220.0 193.0 197.0 197.0 15:37:13 INFO - PROCESS | 1682 | 15:37:13 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:76.80 Median:64.00 stddev:29.93 (46.8%) stddev-sans-first:31.64 15:37:13 INFO - PROCESS | 1682 | Values: 70.0 69.0 80.0 145.0 55.0 56.0 62.0 66.0 114.0 51.0 15:37:13 INFO - PROCESS | 1682 | 15:37:13 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:91.60 Median:78.50 stddev:19.41 (24.7%) stddev-sans-first:20.20 15:37:13 INFO - PROCESS | 1682 | Values: 81.0 84.0 125.0 76.0 78.0 78.0 117.0 82.0 79.0 116.0 15:37:13 INFO - PROCESS | 1682 | 15:37:13 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:88.00 stddev:1.41 (1.6%) stddev-sans-first:1.50 15:37:13 INFO - PROCESS | 1682 | Values: 87.0 86.0 88.0 86.0 88.0 85.0 88.0 89.0 88.0 85.0 15:37:13 INFO - PROCESS | 1682 | 15:37:13 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:115.90 Median:123.00 stddev:23.52 (19.1%) stddev-sans-first:23.93 15:37:13 INFO - PROCESS | 1682 | Values: 97.0 152.0 104.0 151.0 138.0 94.0 115.0 120.0 94.0 94.0 15:37:13 INFO - PROCESS | 1682 | 15:37:13 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:91.10 Median:91.00 stddev:1.37 (1.5%) stddev-sans-first:1.39 15:37:13 INFO - PROCESS | 1682 | Values: 90.0 91.0 91.0 89.0 92.0 92.0 91.0 94.0 90.0 91.0 15:37:13 INFO - PROCESS | 1682 | 15:37:13 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:68.70 Median:66.50 stddev:9.33 (14.0%) stddev-sans-first:9.48 15:37:13 INFO - PROCESS | 1682 | Values: 61.0 64.0 67.0 66.0 67.0 66.0 62.0 81.0 63.0 90.0 15:37:13 INFO - PROCESS | 1682 | 15:37:13 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:73.40 Median:75.50 stddev:11.85 (15.7%) stddev-sans-first:11.96 15:37:13 INFO - PROCESS | 1682 | Values: 63.0 65.0 65.0 67.0 77.0 74.0 77.0 60.0 93.0 93.0 15:37:13 INFO - PROCESS | 1682 | 15:37:13 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:88.20 Median:82.50 stddev:11.63 (14.1%) stddev-sans-first:12.12 15:37:13 INFO - PROCESS | 1682 | Values: 82.0 78.0 83.0 83.0 95.0 107.0 109.0 76.0 82.0 87.0 15:37:13 INFO - PROCESS | 1682 | 15:37:13 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:150.50 stddev:14.76 (9.8%) stddev-sans-first:13.56 15:37:13 INFO - PROCESS | 1682 | Values: 126.0 149.0 160.0 137.0 150.0 151.0 157.0 133.0 174.0 133.0 15:37:13 INFO - PROCESS | 1682 | 15:37:13 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.80 Median:175.00 stddev:23.06 (13.2%) stddev-sans-first:24.06 15:37:13 INFO - PROCESS | 1682 | Values: 171.0 172.0 170.0 195.0 160.0 222.0 224.0 178.0 169.0 167.0 15:37:13 INFO - PROCESS | 1682 | 15:37:13 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.80 Median:69.50 stddev:8.66 (12.5%) stddev-sans-first:9.17 15:37:13 INFO - PROCESS | 1682 | Values: 70.0 69.0 72.0 69.0 96.0 68.0 66.0 71.0 69.0 68.0 15:37:13 INFO - PROCESS | 1682 | -------- Summary: end -------- 15:37:13 INFO - PROCESS | 1682 | 15:37:14 INFO - TEST-INFO | 1682: exit 0 15:37:14 INFO - TEST-OK | kraken | took 59679ms 15:37:14 INFO - SUITE-END | took 1170s 15:37:14 INFO - Completed test suite (00:19:30) 15:37:14 INFO - javascript benchmark 15:37:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2657.813953023288, 3442.7073698735608, 3459.308138372326, 3486.686198242158, 3506.070891213827, 5675.943240567593, 5680.488756011958, 5686.573506986977, 5699.943000569993, 5702.800401985931, 5611.523020543259, 5695.943040569593, 5701.429857014296, 5743.4543718346695, 5744.109663002241, 5128.358955130618, 5146.125158723026, 5182.937497812958, 5232.973835130819, 5264.341957255343, 5105.081085404626, 5200.583953283747, 5231.65994210377, 5236.214567814839, 5328.094223981913, 5175.611829112813, 5210.348706411699, 5211.869703257411, 5211.9576084783, 5213.296205012312, 5165.81919632813, 5203.479652034785, 5216.008958297949, 5223.764930578133, 5225.346831646044, 4977.178765503688, 4994.575461085812, 5093.032323858455, 5095.745212739348, 5207.4011488678525, 4699.78850951708, 5514.944850551524, 5630.605857589971, 5675.886482270332, 5680.914786278209, 5317.9468205317835, 6041.939580604182, 6125.78559750411, 6203.596766210227, 6239.594426362272, 6211.34780848009, 6275.435210831001, 6279.434850863444, 6294.276158241815, 6294.527910406738, 5780.3063632363965, 5788.797392091299, 5802.622829516068, 5822.33043200029, 5822.534197264251, 6218.751249950042, 6234.3453937336835, 6274.623522588659, 6290.465310448663, 6297.748090076438, 6087.482563982023, 6124.0, 6140.10633425593, 6168.568200225941, 6186.566940314135, 6228.408301211378, 6258.874822503524, 6274.435300822948, 6299.370062993664, 6325.683715814191, 6053.636781793107, 6120.969395153084, 6173.9073913891325, 6194.845128871814, 6208.0, 6208.379162083756, 6231.657258850807, 6250.562460627803, 6277.560570760094, 6281.466075383643, 4952.356193694796, 4958.752062396866, 4964.379452568429, 4973.1297023020825, 4974.129527332702, 4257.27626303523, 4371.715838470553, 4422.646188304928, 4432.047109871393, 4445.911081778347, 5166.121759300928, 5395.570173903919, 5752.913706294409, 5754.5971781975695, 5774.68239246837, 6265.843353916189, 6279.96860015697, 6289.496840252768, 6314.116023756677, 6314.652694101868, 5559.16612508127, 5679.574031947621, 5742.913856292159, 5744.540436765049, 5761.135829625589, 5339.7063161526485, 5375.462453754593, 5380.757865896044, 5380.892382152335, 5395.73021348931, 6206.410391012847, 6329.3354197809485, 6335.873282534323, 6348.206474190726, 6360.950443176824, 5816.94183058164, 6186.536009799193, 6212.099245609271, 6262.06069089622, 6274.717637706405, 6271.184745982993, 6313.43179113882, 6321.778737744157, 6336.651484168415, 6337.6197428154455, 6049.818505444844, 6112.898653789679, 6205.844853878689, 6208.192934918612, 6231.065340199007, 6204.472619827366, 6242.701029185886, 6246.562740608114, 6286.465650419766, 6311.179546658905, 5113.948860511347, 5989.640621562546, 6093.421124993119, 6108.786192483241, 6122.357152498924, 5894.528437724886, 6063.818085457443, 6120.938790612215, 6139.079138129317, 6153.938460615515, 5812.912806307993, 5913.46778789911, 5957.672328022, 5982.641041537521, 5997.340292567901, 6080.604760690541, 6125.84685382869, 6159.168512250968, 6166.044263139221, 6167.321594624677, 5803.738831752666, 5936.762529498772, 5962.731677074629, 6009.399060093956, 6045.516358691206], "value": 5673.569317168139, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.9343585333906, 539.2935254816191, 541.9891602167957, 552.2213678713014, 557.3395526301335, 38775.61224387755, 39523.20953580927, 39819.80090099553, 39996.0, 40029.0, 43484.13031739363, 43557.12885742283, 44221.0, 44489.55510444895, 44509.554904451026, 9861.309708320421, 9938.850917236246, 9982.201423886108, 10001.899981000206, 10006.849897251546, 9750.171235444996, 9810.411375317468, 9841.852372214387, 9908.603655853758, 9919.851202231972, 9845.458499782544, 9856.35933664313, 9869.161121304698, 9895.208383329353, 9973.252006099536, 9765.560549775242, 9845.90154098457, 9874.0, 9875.60497580096, 9885.060919212663, 9788.95105524475, 9904.851427228597, 9924.80150396988, 9969.90030099697, 9980.55087521056, 40223.195536089115, 40687.38968915468, 40922.590774092474, 41517.75446736603, 41565.58434415647, 2803.593478945562, 3136.2316232523176, 3223.5003574446, 3270.787398819093, 3317.402867483852, 22887.88556057209, 27606.585901211496, 27737.445251094865, 28392.574111388345, 28908.710912890812, 2738.3701748597737, 2804.8317100973804, 2809.2415047937147, 2849.487092323381, 2922.21100302917, 484420.0, 489070.0, 490928.0, 493764.53117734543, 495716.0, 35033.29933401318, 35781.0, 68056.0, 69681.30318696747, 69791.0, 36366.0, 36544.903652890454, 36571.08572285714, 36585.0, 36665.26669466596, 15479.84520154784, 15657.530274091796, 15734.842651573337, 15755.4485593006, 15768.68462630741, 10971.561137554569, 11352.375619341015, 12883.806742898863, 13207.933960330136, 13287.269200193889, 6887.345702158287, 8306.377021723394, 8315.625796839156, 8411.0, 8457.154284571494, 3426.554547908756, 3433.3305005524185, 3484.1463841359027, 3486.4073107571776, 3516.331896939573, 877.3113106211594, 920.8351435434181, 979.3878825733916, 1075.7310672331917, 1150.6433005768238, 23417.648735268984, 30923.226919327197, 31436.0, 31588.894388696284, 33124.834375827966, 858.121865223216, 893.6202114101611, 1021.5658345203259, 1059.6079450603327, 1068.4978060311641, 15331.310091045929, 15470.5358839235, 15573.987690800062, 15600.0, 15854.603634908988, 679901.0, 681322.0, 685856.0, 690794.0, 691063.5446822734, 62296.0, 64783.67608162023, 64910.35089649138, 64913.0, 64928.35071649317, 1296.3712599389478, 1493.104137517481, 1505.2022428113119, 1515.9242037898061, 1517.3930427828736, 24061.759382406653, 26660.733392665825, 30484.0, 30782.692173078878, 31331.74673013054, 46450.0, 57101.42898570961, 57211.427885722274, 57782.7110864443, 57836.0, 541359.0, 588023.0, 604112.0, 607074.0, 608965.0, 27722.722772772013, 27994.160175193963, 28172.85913570419, 28371.290717731397, 28436.004739834832, 48522.5147748518, 48776.0, 49982.0, 50046.99906001933, 50966.49033509766, 39570.40644390394, 40051.79974100111, 40392.0, 40749.79625101855, 40845.0, 39127.60872391354, 39174.41238381484, 39491.0, 39912.30904598716, 39927.600723992386, 32957.34085318328, 38233.617663823, 39276.2144757109, 39298.60701393064, 39377.60622393739, 31968.68031319657, 33127.33745325128, 33368.0, 33423.83288083544, 33436.99689009334, 24210.87894560516, 24499.63250551279, 24638.0, 24757.50484990254, 24931.0], "value": 18909.40118085903, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.17867389145863, 407.8633657724662, 413.92963196256636, 415.1178745166521, 422.5605370414769, 14530.34613442395, 16112.436064737736, 20422.0, 20535.589288214247, 20541.691874621894, 19876.40370788875, 20561.383158505232, 23319.650205246933, 24095.32612225526, 24155.0, 6545.509086818483, 6886.552374095692, 6988.965055174717, 6989.231184569693, 6990.790276291719, 5613.326400831886, 6530.346965303455, 6753.594784312957, 6943.270956549565, 6952.826179345506, 5978.701064946757, 6852.5203235773515, 6939.37545620894, 6943.652817359137, 6952.2700116487795, 6687.866242675168, 6900.827479313007, 6920.377166055054, 6964.965175174092, 7001.334873187039, 6505.902411463831, 6759.797206083826, 6813.11429514165, 6814.659267036628, 6839.1451068616425, 28545.143645690456, 29517.557236641253, 30399.24001899948, 30573.694263057307, 30929.072127835974, 1752.6319472910704, 2048.5185981294385, 2297.379707478988, 2345.179187284437, 2438.9634155487684, 12536.310502922335, 15528.930624075547, 16086.919565402099, 17073.40243091486, 17181.711371647052, 1802.97295540567, 1840.7146892231726, 1854.360245715226, 1923.903804809768, 1961.3135402609116, 61535.692321538554, 99737.0, 104840.0, 119538.0, 122606.77393226043, 21818.581792183457, 22535.88732056362, 22576.887115564317, 22718.09127634876, 22763.634181948804, 36953.630463695554, 37055.0, 37595.62404375976, 37609.87170384893, 37735.05662358463, 11400.600978965696, 11472.31166130035, 11650.242734222249, 11657.125715571367, 11711.648650540497, 9427.292953028551, 9522.666706665234, 9524.476153811607, 9546.713598592054, 9586.568604412818, 10261.0, 10357.585696572052, 10433.373997560171, 10456.424896630606, 10479.10927571165, 3878.166194268246, 3941.2117576484816, 3981.5819338969573, 3981.7212795104065, 4086.3461846105074, 827.2017503109408, 916.276141847946, 946.4605175050293, 991.945443000642, 992.2409356842022, 19926.003699815243, 20943.581128377347, 20954.685679714818, 21106.78893211079, 21184.258550950643, 951.8762560867043, 954.9236061115233, 958.712386284112, 967.0909345215475, 980.5881529757519, 10736.0, 10964.013238808546, 11000.0, 11016.449177541092, 11044.447777611247, 513710.0, 632311.0, 637139.0, 637194.0, 640033.0, 112494.0, 114510.70978580382, 133952.33023835014, 135269.0, 141373.29313353368, 837.874318852177, 1012.4128005756644, 1076.6554702495127, 1091.8689757229024, 1092.355510248957, 18171.0, 18639.0, 19185.10335492973, 19566.510837229183, 19655.0, 55285.0, 55319.89360212853, 55781.884362313336, 56270.264616671964, 56326.8734625297, 81034.0, 81228.1570145042, 82691.45197014223, 82851.0, 83017.58491207747, 7320.487565870338, 7403.5928023959195, 8392.4544904581, 8425.452345597516, 8484.745457636282, 12316.815247771112, 12732.490700371882, 12888.548900788803, 12937.741245175232, 13124.278164701032, 10985.945070274598, 11254.662360129209, 11514.366709830705, 11552.94223528877, 11616.954474097372, 8616.095309992612, 10254.769427668727, 10784.622538211124, 11031.117510599293, 11392.601258955896, 8857.64569417242, 10835.620753273597, 11795.351255680676, 11978.64064078079, 12027.579034733742, 7924.0, 8203.8104474852, 8410.453320534145, 8692.652293908173, 8793.20861122502, 9399.718008459757, 9652.710418687451, 11317.603883864025, 11537.192396532162, 11612.593559225386], "value": 11002.834179375846, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1588.3329001819236, 1614.1445034131907, 1886.6698327792633, 1975.2099160335872, 1986.324649619131, 7865.449418540705, 8578.39951203416, 8755.824883502342, 9086.681966131187, 9088.500132492718, 8773.0, 8854.557272136399, 8875.91124088759, 8879.511626860536, 8922.107789221074, 2454.5336386086587, 2486.0925762096886, 2489.5892177790643, 2500.1499490173337, 2505.210858579551, 2433.2335314375914, 2493.463905260369, 2497.3631723910376, 2498.2380373985925, 2520.8991640334457, 2457.496213276274, 2465.987670061656, 2510.309664842174, 2512.371907023244, 2512.798976081918, 2470.17249221511, 2471.938201544967, 2477.9008839646394, 2491.4145175883655, 2492.264781889347, 2422.4065104049446, 2434.257551446808, 2441.023590563771, 2441.816863735209, 2482.925512234636, 9032.09679032098, 9088.045755195675, 9098.044705305914, 9154.450732956044, 9208.309376796768, 5320.281761962163, 5482.205080263379, 5523.447655234444, 5552.08390615548, 5555.861103472405, 3620.2035552178477, 3651.8174091295596, 3657.9451308230396, 3706.1105334719473, 3709.4250391189407, 1021.5556233038652, 1045.2578669144916, 1047.1256500821823, 1067.6796960911695, 1100.3178029621606, 9977.451240181786, 11658.183927125105, 11837.171398002143, 11843.822342664867, 11891.762164756743, 4489.910201795946, 4821.95178048215, 4887.193613053877, 4948.480409557017, 4993.976234871842, 9430.575624096931, 10035.849462258071, 10108.696739097839, 10137.594496220068, 10180.134688551409, 5420.783168673209, 5469.753861076261, 5519.834404967857, 5524.861878453071, 5545.805896793593, 4497.190505708938, 4888.388951381077, 4972.925406118911, 5054.494550544916, 5066.290719299301, 3164.683531646817, 4056.411820286042, 4120.456249753342, 4160.313548264563, 4180.393842892824, 1371.081375478432, 1371.8285214348207, 1372.9244891530861, 1373.8557451467652, 1386.9736395067578, 910.2945217456391, 930.734740598926, 932.379967321737, 934.7008957133788, 935.8596210568469, 5778.479936805707, 5785.0454674978155, 5826.863761566538, 5878.235829342244, 5895.882082358329, 457.83517933543897, 471.9764011799396, 472.92433210686124, 477.2316570321779, 481.0163216222763, 3317.668233176663, 3697.205100903319, 3847.3459511883048, 3884.6892248620043, 3925.4700615416555, 10572.788544229072, 10985.0, 11001.449927503752, 11081.889181108085, 11092.168087393316, 10344.37933723979, 10902.182336324808, 10959.890401096047, 10978.011978921935, 10997.285176463663, 8255.793605159919, 8351.916480835236, 8366.958165209257, 8371.079181289932, 8391.790205244917, 5597.076482380525, 5628.690422026828, 5632.605717599728, 5684.573656975826, 5690.089585666439, 10024.699259022242, 10080.949595251977, 10116.494175291353, 10135.037171468699, 10193.337433066827, 9856.408615483095, 10078.748031299276, 10252.794944101226, 10471.162307015267, 10489.842652360068, 5329.893402131858, 6718.764843230463, 6850.0409942607075, 7201.459890508128, 7586.355159811478, 8361.456505327134, 8394.538300393537, 8436.0, 8437.240648341678, 8445.746627601182, 7389.334959853639, 7597.3162415382885, 7666.0, 7670.850076054561, 7823.804404889924, 6902.551334163464, 7220.3140701633265, 7509.812254693677, 7574.545527268381, 7617.6572054256585, 6902.896456553256, 6953.096097507291, 6968.5122041458635, 7005.019297298484, 7035.613041282779, 4748.09786140646, 4777.522247775195, 4856.781444835062, 4857.344258525054, 4878.414590249193, 4878.536539028786, 4883.658143929891, 4902.926456103232, 4998.500149985118, 5081.796728130834], "value": 4575.283264859445, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.9631644202696, 308.1602632825551, 309.6578280999497, 315.85312829534263, 317.5474948198819, 5202.791888324463, 5483.38096856075, 5529.751161197751, 5530.760042182912, 5578.972105139474, 5329.546988505972, 5415.7002319443245, 5437.13005919053, 5454.863628409292, 5460.34475862896, 3382.7293816494685, 3398.4392575225056, 4179.686523510733, 4192.371144328345, 4195.2029114468305, 3926.802325290785, 3951.5850835662272, 4137.234611596849, 4176.227397931377, 4192.790360481979, 3783.451399547064, 4157.251694694954, 4166.666666666674, 4171.645410140141, 4188.806190235783, 3628.219932714466, 3642.9271414571826, 3919.862804801818, 4168.7290326128705, 4171.91656166875, 3264.4287249731437, 3507.473878918157, 3528.629493903129, 3562.2697347043795, 3604.621514740967, 4757.286407038937, 4896.951030489685, 5320.707361095138, 5357.249985002064, 5408.702521361323, 3825.674817640504, 4012.378081397359, 4465.82136714531, 4755.738434386108, 4813.229883218668, 5634.2389133040515, 5822.359540450547, 5829.708514574297, 5844.620099693507, 5864.736086876101, 3307.570015897918, 3850.152966347399, 3956.990967303348, 3957.3074711925296, 3961.187956468917, 5620.5503559715535, 5624.55003599711, 5790.7973220937065, 5832.154337621064, 6122.969385153046, 3963.36586146221, 3997.6601988830694, 4005.178938317638, 4011.7592944423427, 4020.7386519876113, 3109.1395915757557, 3350.4304268273963, 4048.2713111640187, 4068.532118806316, 4138.089620283533, 4611.815527378934, 4704.200285951397, 4773.64197685175, 4791.161546729378, 4809.422869255712, 4741.644376671763, 5878.970605146947, 5886.323072846678, 5902.4097590240635, 5924.7926322578505, 1223.7797196504537, 1462.5612316305067, 1477.1683112939877, 1480.000999325451, 1480.289461058698, 892.5715656484923, 944.2964991081689, 948.9699380225121, 955.4267439536222, 970.3401686853008, 5740.885182296331, 5757.193992841006, 5781.277340332458, 5782.826515204551, 5810.6513609183585, 6128.233970753656, 6337.588056776295, 6508.414242718178, 6538.574992625464, 6554.311797261219, 5495.818399044225, 5496.560275177976, 5743.4256574342235, 5782.421757824268, 5784.913226301609, 2539.7460253974823, 3401.1837159081642, 3410.6077801053198, 3412.2663627320244, 3413.4709120086427, 5350.812721554805, 6392.872142557123, 6783.219929708048, 6796.796096117125, 6801.727930882808, 5530.557555395559, 6246.875062498815, 6267.874642507216, 6286.245650521967, 6290.339514351019, 6235.750569977332, 6384.691138316634, 6410.884408145301, 6415.743370265137, 6430.839229019219, 5884.47039766423, 6068.9089663654195, 6085.391460854056, 6106.664133472702, 6123.326434092335, 4885.438174609965, 5054.458390191027, 5977.193078934288, 6028.758849645965, 6117.755289788359, 5599.99200143978, 6007.699615019319, 6285.622862628257, 6327.240731112296, 6375.776847810304, 4078.0416602099076, 4153.543110257809, 4180.888651231109, 4181.912702697381, 4183.477065366829, 5420.430854760193, 5677.694130350125, 6071.423214794588, 6075.939240607538, 6091.238595175603, 5038.269450929522, 5265.6050796189675, 5601.80393686219, 5611.933732590958, 5766.4233576642, 4713.9057218856115, 4753.786079626494, 5319.973400132974, 5812.041013232938, 5833.970830145992, 4827.517248275144, 5494.560435165097, 5629.6903670298525, 5658.632188907708, 5695.743691533974, 4819.566239038503, 5115.718635475085, 5173.3533308336455, 5792.768289268382, 5888.998870191992, 4790.856274311776, 5080.7205603692155, 5326.627136100436, 5773.6824474654295, 5809.506191973561], "value": 4269.835588572546, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.2913482573645, 699.765578531192, 740.5741698523348, 744.255744255744, 754.0837881973401, 13399.665008374783, 15001.474948376808, 15158.848411515895, 15216.467423640175, 15224.23878806058, 13847.0, 13912.860871391309, 13916.721665566683, 13947.790783138236, 13949.860501394984, 723.4465633790129, 912.5072460871122, 916.3127654259306, 950.0452045694068, 953.6709835106911, 938.2540880000416, 939.0421769794805, 948.7770373962113, 952.3238500664502, 952.5618215620788, 944.2964991081653, 944.7260294514582, 991.9553620087078, 995.7809281957958, 1006.2754816532085, 952.4904176265665, 971.9562619682131, 973.6154219083458, 999.715081201854, 1001.284081881451, 711.2460791560962, 741.4772585327386, 747.7943565519471, 748.0798617700259, 752.1575835064708, 11981.880181198163, 13477.921766258676, 13493.86506134936, 14723.263836808117, 15165.772513412308, 10955.616553420592, 12602.558910438182, 12636.620901372882, 12865.052871449407, 12871.935640321739, 8102.513849169069, 9886.901130988743, 10073.05612288206, 10210.081092701692, 10260.333078349884, 3989.7606143631183, 4031.9798401007806, 4032.254033003874, 4147.81334839933, 4151.958480415217, 76926.61536692336, 81724.59137704333, 81765.18234817636, 84383.57808210982, 84601.30797384017, 9258.722238332726, 9325.953370233105, 9371.906280937103, 9434.19809316202, 9591.616335346647, 20562.588748224953, 21210.469738256666, 21283.78716212849, 21331.573368532543, 21403.46491337729, 7404.703811847466, 7436.888446673304, 7574.7032744188955, 7826.0, 7844.058712954482, 3534.876279330211, 4099.959000410017, 4117.547069822317, 4119.711620186558, 4128.896777580585, 3532.717382609385, 3673.246984368198, 3683.8158092095287, 3684.894531640497, 3789.3558095123894, 702.2485940044104, 710.1903829634222, 713.6895450478954, 718.052171134098, 718.7340683946868, 496.8757810547363, 520.3261776000089, 572.3735678282555, 574.6121368076532, 584.9795257165979, 7769.689212431439, 8035.517868927883, 8108.391870609727, 8266.545340006358, 8377.70678026266, 561.205893660474, 561.9409961954018, 573.9368669446358, 585.9628457630056, 589.628534023571, 3718.7768733876055, 3817.522809648794, 3818.1981783825154, 3912.5109361329833, 3937.783421911765, 105167.0, 108155.45922270339, 108604.0, 109058.45470772595, 109090.0, 87162.1283787154, 87742.56128719315, 89193.55403222943, 90530.54734726284, 90986.0, 77217.0, 77474.0, 78003.0, 78714.21285787184, 79029.60485197537, 7392.482526223328, 7547.50941188821, 7583.27958843909, 7587.089549254125, 7751.293284819504, 36169.81915090408, 47214.52785472101, 47407.28889066598, 47788.0, 48003.0, 49289.0, 63482.68258658677, 64221.03668444884, 67311.31721706997, 69777.30222697707, 9991.350562213433, 10200.59197632116, 10276.126529244799, 10385.324953877975, 10435.113015393777, 9146.588403521724, 11112.777744445228, 11169.218154729091, 11183.944080279547, 11757.529698812294, 10150.441725705157, 10156.695299141038, 10162.0, 10180.69457916234, 10382.688519344432, 8759.299256059372, 10387.792244155225, 10412.219083568914, 10524.263301569123, 10524.631637892637, 9799.951000245239, 9920.454375009444, 9938.204943604349, 10035.09684128432, 10088.394696318244, 9035.774105647411, 9127.178553930178, 9181.862272065791, 9201.30990175726, 9224.123708247706, 7510.8497830042, 8655.52394618302, 8669.696560620348, 8803.207711306013, 8994.820103598022], "value": 6833.916172501169, "unit": "score"}], "name": "dromaeo_css", "value": 7349.599683798515, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 126.0, 172.0, 122.0, 122.0, 133.0, 126.0, 105.0, 148.0, 169.0], "value": 134.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 130.0, 198.0, 157.0, 123.0, 134.0, 144.0, 184.0, 180.0, 121.0], "value": 152.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 194.0, 199.0, 197.0, 193.0, 193.0, 220.0, 193.0, 197.0, 197.0], "value": 197.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 80.0, 145.0, 55.0, 56.0, 62.0, 66.0, 114.0, 51.0], "value": 76.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 125.0, 76.0, 78.0, 78.0, 117.0, 82.0, 79.0, 116.0], "value": 91.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 86.0, 88.0, 85.0, 88.0, 89.0, 88.0, 85.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 152.0, 104.0, 151.0, 138.0, 94.0, 115.0, 120.0, 94.0, 94.0], "value": 115.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 91.0, 89.0, 92.0, 92.0, 91.0, 94.0, 90.0, 91.0], "value": 91.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 67.0, 66.0, 67.0, 66.0, 62.0, 81.0, 63.0, 90.0], "value": 68.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 65.0, 67.0, 77.0, 74.0, 77.0, 60.0, 93.0, 93.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 78.0, 83.0, 83.0, 95.0, 107.0, 109.0, 76.0, 82.0, 87.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 149.0, 160.0, 137.0, 150.0, 151.0, 157.0, 133.0, 174.0, 133.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 170.0, 195.0, 160.0, 222.0, 224.0, 178.0, 169.0, 167.0], "value": 182.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 72.0, 69.0, 96.0, 68.0, 66.0, 71.0, 69.0, 68.0], "value": 71.8, "unit": "score"}], "name": "kraken", "value": 1578.8, "alertThreshold": 2.0}]} 15:37:14 INFO - Return code: 0 15:37:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:37:14 ERROR - # TBPL SUCCESS # 15:37:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:37:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:37:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:37:14 INFO - 2016-04-29 15:37:14.620 screenresolution[1687:10440] starting screenresolution argv=screenresolution get 15:37:14 INFO - 2016-04-29 15:37:14.623 screenresolution[1687:10440] Display 0: 1600x1200x32@60 15:37:14 INFO - 2016-04-29 15:37:14.629 screenresolution[1688:10443] starting screenresolution argv=screenresolution list 15:37:14 INFO - Available Modes on Display 0 15:37:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:37:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:37:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:37:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:37:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:37:14 INFO - Intel Iris: 15:37:14 INFO - Chipset Model: Intel Iris 15:37:14 INFO - Type: GPU 15:37:14 INFO - Bus: Built-In 15:37:14 INFO - VRAM (Dynamic, Max): 1536 MB 15:37:14 INFO - Vendor: Intel (0x8086) 15:37:14 INFO - Device ID: 0x0a2e 15:37:14 INFO - Revision ID: 0x0009 15:37:14 INFO - Displays: 15:37:14 INFO - Display: 15:37:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:37:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:37:14 INFO - Display Serial Number: 1600x1200 60 15:37:14 INFO - Main Display: Yes 15:37:14 INFO - Mirror: Off 15:37:14 INFO - Online: Yes 15:37:14 INFO - Rotation: Supported 15:37:14 INFO - Return code: 0 15:37:14 INFO - Running post-action listener: _resource_record_post_action 15:37:14 INFO - Running post-run listener: _resource_record_post_run 15:37:15 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 38127104; Write bytes: 654374400; Read time: 398; Write time: 1891 15:37:15 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345819648; Write bytes: 345699840; Read time: 26637; Write time: 740 15:37:15 INFO - run-tests - Wall time: 1173s; CPU: 54.0%; Read bytes: 37172736; Write bytes: 298721280; Read time: 381; Write time: 1117 15:37:15 INFO - Running post-run listener: _upload_blobber_files 15:37:15 INFO - Blob upload gear active. 15:37:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:37:15 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 15:37:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:37:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:37:16 INFO - (blobuploader) - INFO - Open directory for files ... 15:37:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:37:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:37:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:37:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:37:17 INFO - (blobuploader) - INFO - Done attempting. 15:37:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:37:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:37:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:37:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:37:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:37:18 INFO - (blobuploader) - INFO - Done attempting. 15:37:18 INFO - (blobuploader) - INFO - Iteration through files over. 15:37:18 INFO - Return code: 0 15:37:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:37:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:37:18 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b62cd9ec151c4a85f3dd05a83e5575a80d97b7bd0ef0ca9751ff847ec178091c5ffb3a37e47e7a8573fc6ed9b4126cfabc779f2c1b20cec51586803edbdfa706", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f087f8694bf7ab6a22800d532cc4736aad3f701160ab8a6d12ad63937e12ad00b74490febd215db4e83da78d0c9a55ea63365d7369eb552cd60487dee189314a"} 15:37:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:37:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:37:18 INFO - Contents: 15:37:18 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b62cd9ec151c4a85f3dd05a83e5575a80d97b7bd0ef0ca9751ff847ec178091c5ffb3a37e47e7a8573fc6ed9b4126cfabc779f2c1b20cec51586803edbdfa706", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f087f8694bf7ab6a22800d532cc4736aad3f701160ab8a6d12ad63937e12ad00b74490febd215db4e83da78d0c9a55ea63365d7369eb552cd60487dee189314a"} 15:37:18 INFO - Running post-run listener: copy_logs_to_upload_dir 15:37:18 INFO - Copying logs to upload dir... 15:37:18 INFO - mkdir: /builds/slave/test/build/upload/logs 15:37:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.566515 ========= master_lag: 4.51 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-04-29 15:37:23.042356) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-29 15:37:23.047564) ========= 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.c1H1xDDWB1/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.iyamyUbuxl/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b62cd9ec151c4a85f3dd05a83e5575a80d97b7bd0ef0ca9751ff847ec178091c5ffb3a37e47e7a8573fc6ed9b4126cfabc779f2c1b20cec51586803edbdfa706", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f087f8694bf7ab6a22800d532cc4736aad3f701160ab8a6d12ad63937e12ad00b74490febd215db4e83da78d0c9a55ea63365d7369eb552cd60487dee189314a"} build_url:https://queue.taskcluster.net/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010898 build_url: 'https://queue.taskcluster.net/v1/task/XwIgNZ94SeGy20Rq6i09Uw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b62cd9ec151c4a85f3dd05a83e5575a80d97b7bd0ef0ca9751ff847ec178091c5ffb3a37e47e7a8573fc6ed9b4126cfabc779f2c1b20cec51586803edbdfa706", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f087f8694bf7ab6a22800d532cc4736aad3f701160ab8a6d12ad63937e12ad00b74490febd215db4e83da78d0c9a55ea63365d7369eb552cd60487dee189314a"}' ========= master_lag: 0.89 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-29 15:37:23.950087) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-04-29 15:37:23.950429) ========= 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.c1H1xDDWB1/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.iyamyUbuxl/Listeners TMPDIR=/var/folders/10/1x852mss3jl9sjlsx99svtn400000w/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.005095 ========= master_lag: 5.82 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-04-29 15:37:29.773506) ========= ========= Started reboot skipped (results: 3, elapsed: 1 secs) (at 2016-04-29 15:37:29.773814) ========= ========= Finished reboot skipped (results: 3, elapsed: 1 secs) (at 2016-04-29 15:37:31.691732) ========= ========= Total master_lag: 11.28 =========