builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0210 starttime: 1468005665.85 results: success (0) buildid: 20160708040727 builduid: 75f4324d657349a998af2caa2550aa8a revision: 24e82cb41040b1f515135ae3ba8b6bddf234d509 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:05.854670) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:05.855132) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:05.855529) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:06.293198) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:06.293456) ========= 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.25zIUEcJsC/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.YtyTLGDyri/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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-07-08 12:21:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 178M=0s 2016-07-08 12:21:06 (178 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.346668 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:06.723160) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:06.723439) ========= 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.25zIUEcJsC/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.YtyTLGDyri/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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.027047 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:06.772965) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 12:21:06.773303) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 24e82cb41040b1f515135ae3ba8b6bddf234d509 --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-beta --rev 24e82cb41040b1f515135ae3ba8b6bddf234d509 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.25zIUEcJsC/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.YtyTLGDyri/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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-07-08 12:21:06,861 truncating revision to first 12 chars 2016-07-08 12:21:06,861 Setting DEBUG logging. 2016-07-08 12:21:06,861 attempt 1/10 2016-07-08 12:21:06,861 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/24e82cb41040?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-08 12:21:07,591 unpacking tar archive at: mozilla-beta-24e82cb41040/testing/mozharness/ program finished with exit code 0 elapsedTime=1.002894 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 12:21:07.811240) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:07.811507) ========= script_repo_revision: 24e82cb41040b1f515135ae3ba8b6bddf234d509 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:07.811871) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:07.812173) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 12:21:07.863324) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-08 12:21:07.863628) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.25zIUEcJsC/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.YtyTLGDyri/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:21:08 INFO - MultiFileLogger online at 20160708 12:21:08 in /builds/slave/test 12:21:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 12:21:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:21:08 INFO - {'append_to_log': False, 12:21:08 INFO - 'base_work_dir': '/builds/slave/test', 12:21:08 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:21:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:21:08 INFO - 'branch': 'Mozilla-Beta', 12:21:08 INFO - 'buildbot_json_path': 'buildprops.json', 12:21:08 INFO - 'config_files': ('talos/mac_config.py',), 12:21:08 INFO - 'default_actions': ('clobber', 12:21:08 INFO - 'read-buildbot-config', 12:21:08 INFO - 'download-and-extract', 12:21:08 INFO - 'populate-webroot', 12:21:08 INFO - 'create-virtualenv', 12:21:08 INFO - 'install', 12:21:08 INFO - 'run-tests'), 12:21:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:21:08 INFO - 'download_minidump_stackwalk': True, 12:21:08 INFO - 'download_symbols': 'ondemand', 12:21:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:21:08 INFO - 'tooltool.py': '/tools/tooltool.py', 12:21:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:21:08 INFO - '/tools/misc-python/virtualenv.py')}, 12:21:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:21:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:21:08 INFO - 'installer_path': 'installer.exe', 12:21:08 INFO - 'log_level': 'info', 12:21:08 INFO - 'log_name': 'talos', 12:21:08 INFO - 'log_to_console': True, 12:21:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:21:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:21:08 INFO - 'opt_config_files': (), 12:21:08 INFO - 'pip_index': False, 12:21:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:21:08 INFO - 'cmd': ('bash', 12:21:08 INFO - '-c', 12:21:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:21:08 INFO - 'enabled': True, 12:21:08 INFO - 'halt_on_failure': False, 12:21:08 INFO - 'name': 'check_screen_resolution'},), 12:21:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:21:08 INFO - 'cmd': ('bash', 12:21:08 INFO - '-c', 12:21:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:21:08 INFO - 'enabled': True, 12:21:08 INFO - 'halt_on_failure': False, 12:21:08 INFO - 'name': 'check_screen_resolution'},), 12:21:08 INFO - 'run_cmd_checks_enabled': True, 12:21:08 INFO - 'sps_profile': False, 12:21:08 INFO - 'sps_profile_interval': 0, 12:21:08 INFO - 'suite': 'dromaeojs-e10s', 12:21:08 INFO - 'system_bits': '32', 12:21:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:21:08 INFO - 'title': 't-yosemite-r7-0210', 12:21:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:21:08 INFO - 'use_talos_json': True, 12:21:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:21:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:21:08 INFO - 'work_dir': 'build'} 12:21:08 INFO - ##### 12:21:08 INFO - ##### Running clobber step. 12:21:08 INFO - ##### 12:21:08 INFO - Running pre-action listener: _resource_record_pre_action 12:21:08 INFO - Running main action method: clobber 12:21:08 INFO - rmtree: /builds/slave/test/build 12:21:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:21:09 INFO - Running post-action listener: _resource_record_post_action 12:21:09 INFO - ##### 12:21:09 INFO - ##### Running read-buildbot-config step. 12:21:09 INFO - ##### 12:21:09 INFO - Running pre-action listener: _resource_record_pre_action 12:21:09 INFO - Running main action method: read_buildbot_config 12:21:09 INFO - Using buildbot properties: 12:21:09 INFO - { 12:21:10 INFO - "project": "", 12:21:10 INFO - "product": "firefox", 12:21:10 INFO - "script_repo_revision": "production", 12:21:10 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 12:21:10 INFO - "repository": "", 12:21:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 12:21:10 INFO - "buildid": "20160708040727", 12:21:10 INFO - "pgo_build": "False", 12:21:10 INFO - "basedir": "/builds/slave/test", 12:21:10 INFO - "buildnumber": 33, 12:21:10 INFO - "slavename": "t-yosemite-r7-0210", 12:21:10 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:21:10 INFO - "platform": "yosemite_r7", 12:21:10 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 12:21:10 INFO - "branch": "mozilla-beta", 12:21:10 INFO - "repo_path": "releases/mozilla-beta", 12:21:10 INFO - "revision": "24e82cb41040b1f515135ae3ba8b6bddf234d509", 12:21:10 INFO - "stage_platform": "macosx64", 12:21:10 INFO - "builduid": "75f4324d657349a998af2caa2550aa8a", 12:21:10 INFO - "slavebuilddir": "test" 12:21:10 INFO - } 12:21:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 12:21:10 INFO - Running post-action listener: _resource_record_post_action 12:21:10 INFO - ##### 12:21:10 INFO - ##### Running download-and-extract step. 12:21:10 INFO - ##### 12:21:10 INFO - Running pre-action listener: _resource_record_pre_action 12:21:10 INFO - Running main action method: download_and_extract 12:21:10 INFO - mkdir: /builds/slave/test/build/tests 12:21: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')]} 12:21:10 INFO - https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:21:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 12:21:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 12:21:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 12:21:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 12:21:11 INFO - Downloaded 1165 bytes. 12:21:11 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 12:21:11 INFO - Using the following test package requirements: 12:21:11 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 12:21:11 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 12:21:11 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 12:21:11 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 12:21:11 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 12:21:11 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:21:11 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 12:21:11 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 12:21:11 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 12:21:11 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 12:21:11 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 12:21:11 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 12:21:11 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 12:21:11 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 12:21:11 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 12:21:11 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 12:21:11 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 12:21:11 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 12:21:11 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')]} 12:21:11 INFO - https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:21:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 12:21:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 12:21:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 12:21:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 12:21:12 INFO - Downloaded 18351527 bytes. 12:21:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:21:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:21:15 INFO - Return code: 0 12:21:15 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 12:21:15 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')]} 12:21:15 INFO - https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:21:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 12:21:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 12:21:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 12:21:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 12:21:16 INFO - Downloaded 18351527 bytes. 12:21:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:21:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:21:20 INFO - Return code: 0 12:21:20 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')]} 12:21:20 INFO - https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:21:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 12:21:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 12:21:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 12:21:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 12:21:22 INFO - Downloaded 10840145 bytes. 12:21:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:21:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:21:22 INFO - Return code: 0 12:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:21:22 INFO - https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 12:21:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 12:21:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 12:21:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 12:21:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 12:21:25 INFO - Downloaded 86183753 bytes. 12:21:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 12:21:25 INFO - mkdir: /builds/slave/test/properties 12:21:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:21:25 INFO - Writing to file /builds/slave/test/properties/build_url 12:21:25 INFO - Contents: 12:21:25 INFO - build_url:https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg 12:21:27 INFO - Running post-action listener: _resource_record_post_action 12:21:27 INFO - Running post-action listener: set_extra_try_arguments 12:21:27 INFO - ##### 12:21:27 INFO - ##### Running populate-webroot step. 12:21:27 INFO - ##### 12:21:27 INFO - Running pre-action listener: _resource_record_pre_action 12:21:27 INFO - Running main action method: populate_webroot 12:21:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:21:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:21:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:21:27 INFO - '--tppagecycles', 12:21:27 INFO - '7'], 12:21:27 INFO - 'tests': ['tsvgm']}}, 12:21:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:21:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:21:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:21:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:21:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:21:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:21:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:27 INFO - 'talos_options': ['--disable-e10s'], 12:21:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:21:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:21:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:27 INFO - 'talos_options': ['--disable-e10s'], 12:21:27 INFO - 'tests': ['damp', 'tps']}, 12:21:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:27 INFO - 'tests': ['damp', 'tps']}, 12:21:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:21:27 INFO - 'tests': ['dromaeo_dom']}, 12:21:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:21:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:21:27 INFO - 'tests': ['a11yr', 12:21:27 INFO - 'ts_paint', 12:21:27 INFO - 'tpaint', 12:21:27 INFO - 'sessionrestore', 12:21:27 INFO - 'sessionrestore_no_auto_restore', 12:21:27 INFO - 'tabpaint']}, 12:21:27 INFO - 'other-e10s': {'tests': ['a11yr', 12:21:27 INFO - 'ts_paint', 12:21:27 INFO - 'tpaint', 12:21:27 INFO - 'sessionrestore', 12:21:27 INFO - 'sessionrestore_no_auto_restore', 12:21:27 INFO - 'tabpaint']}, 12:21:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:21:27 INFO - 'ts_paint', 12:21:27 INFO - 'tpaint', 12:21:27 INFO - 'sessionrestore', 12:21:27 INFO - 'sessionrestore_no_auto_restore', 12:21:27 INFO - 'tabpaint']}, 12:21:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:21:27 INFO - 'ts_paint', 12:21:27 INFO - 'tpaint', 12:21:27 INFO - 'sessionrestore', 12:21:27 INFO - 'sessionrestore_no_auto_restore', 12:21:27 INFO - 'tabpaint']}, 12:21:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:21:27 INFO - 'tests': ['a11yr', 12:21:27 INFO - 'ts_paint', 12:21:27 INFO - 'tpaint', 12:21:27 INFO - 'sessionrestore', 12:21:27 INFO - 'sessionrestore_no_auto_restore', 12:21:27 INFO - 'tabpaint']}, 12:21:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:21:27 INFO - 'tests': ['a11yr', 12:21:27 INFO - 'ts_paint', 12:21:27 INFO - 'tpaint', 12:21:27 INFO - 'sessionrestore', 12:21:27 INFO - 'sessionrestore_no_auto_restore', 12:21:27 INFO - 'tabpaint']}, 12:21:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:21:27 INFO - 'tests': ['tsvgx', 12:21:27 INFO - 'tsvgr_opacity', 12:21:27 INFO - 'tart', 12:21:27 INFO - 'tscrollx', 12:21:27 INFO - 'cart']}, 12:21:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:21:27 INFO - 'tsvgr_opacity', 12:21:27 INFO - 'tart', 12:21:27 INFO - 'tscrollx', 12:21:27 INFO - 'cart']}, 12:21:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:27 INFO - 'talos_options': ['--disable-e10s'], 12:21:27 INFO - 'tests': ['tp5o']}, 12:21:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:27 INFO - 'tests': ['tp5o']}, 12:21:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:27 INFO - 'talos_options': ['--disable-e10s', 12:21:27 INFO - '--xperf_path', 12:21:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:21:27 INFO - 'tests': ['tp5n']}, 12:21:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:21:27 INFO - 'talos_options': ['--xperf_path', 12:21:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:21:27 INFO - 'tests': ['tp5n']}}, 12:21:27 INFO - 'talos.zip': {'path': '', 12:21:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:21:27 INFO - Running post-action listener: _resource_record_post_action 12:21:27 INFO - ##### 12:21:27 INFO - ##### Running create-virtualenv step. 12:21:27 INFO - ##### 12:21:27 INFO - Running pre-action listener: _resource_record_pre_action 12:21:27 INFO - Running main action method: create_virtualenv 12:21:27 INFO - Creating virtualenv /builds/slave/test/build/venv 12:21:27 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 12:21:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:21:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:21:27 INFO - Using real prefix '/tools/python27' 12:21:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:21:28 INFO - Installing distribute.............................................................................................................................................................................................done. 12:21:31 INFO - Installing pip.................done. 12:21:31 INFO - Return code: 0 12:21:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:21:31 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')]} 12:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:31 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')]} 12:21:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:31 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 0x109867a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109877420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe011c9e3a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109706e00>, '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 0x10956fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109701b20>, '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.25zIUEcJsC/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.YtyTLGDyri/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:21:31 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 12:21:31 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 12:21:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.25zIUEcJsC/Render', 12:21:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:31 INFO - 'HOME': '/Users/cltbld', 12:21:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:31 INFO - 'LOGNAME': 'cltbld', 12:21:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:31 INFO - 'MOZ_NO_REMOTE': '1', 12:21:31 INFO - 'NO_EM_RESTART': '1', 12:21:31 INFO - 'PAGER': '/bin/cat', 12:21:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:31 INFO - 'PWD': '/builds/slave/test', 12:21:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:31 INFO - 'SHELL': '/bin/bash', 12:21:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YtyTLGDyri/Listeners', 12:21:31 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:21:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:31 INFO - 'USER': 'cltbld', 12:21:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:31 INFO - 'XPC_FLAGS': '0x0', 12:21:31 INFO - 'XPC_SERVICE_NAME': '0', 12:21:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:31 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 12:21:31 INFO - Cleaning up... 12:21:31 INFO - Return code: 0 12:21:31 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:21:31 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')]} 12:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:31 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')]} 12:21:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:31 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 0x109867a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109877420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe011c9e3a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109706e00>, '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 0x10956fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109701b20>, '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.25zIUEcJsC/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.YtyTLGDyri/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:21:31 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 12:21:31 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 12:21:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.25zIUEcJsC/Render', 12:21:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:31 INFO - 'HOME': '/Users/cltbld', 12:21:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:31 INFO - 'LOGNAME': 'cltbld', 12:21:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:31 INFO - 'MOZ_NO_REMOTE': '1', 12:21:31 INFO - 'NO_EM_RESTART': '1', 12:21:31 INFO - 'PAGER': '/bin/cat', 12:21:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:31 INFO - 'PWD': '/builds/slave/test', 12:21:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:31 INFO - 'SHELL': '/bin/bash', 12:21:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YtyTLGDyri/Listeners', 12:21:31 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:21:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:31 INFO - 'USER': 'cltbld', 12:21:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:31 INFO - 'XPC_FLAGS': '0x0', 12:21:31 INFO - 'XPC_SERVICE_NAME': '0', 12:21:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:31 INFO - Downloading/unpacking psutil>=0.7.1 12:21:31 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 12:21:31 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 12:21:31 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 12:21:31 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 12:21:31 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 12:21:31 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 12:21:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:21:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:21:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:21:34 INFO - Installing collected packages: psutil 12:21:34 INFO - Running setup.py install for psutil 12:21:34 INFO - building 'psutil._psutil_osx' extension 12:21:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:21:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:21:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:21:34 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 12:21:35 INFO - building 'psutil._psutil_posix' extension 12:21:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:21:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:21:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:21:35 INFO - ^ 12:21:35 INFO - 1 warning generated. 12:21:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:21:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:21:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:21:35 INFO - Successfully installed psutil 12:21:35 INFO - Cleaning up... 12:21:35 INFO - Return code: 0 12:21:35 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:21:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109867a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109877420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe011c9e3a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109706e00>, '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 0x10956fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109701b20>, '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.25zIUEcJsC/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.YtyTLGDyri/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:21:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 12:21:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 12:21:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.25zIUEcJsC/Render', 12:21:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:35 INFO - 'HOME': '/Users/cltbld', 12:21:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:35 INFO - 'LOGNAME': 'cltbld', 12:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:35 INFO - 'MOZ_NO_REMOTE': '1', 12:21:35 INFO - 'NO_EM_RESTART': '1', 12:21:35 INFO - 'PAGER': '/bin/cat', 12:21:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:35 INFO - 'PWD': '/builds/slave/test', 12:21:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:35 INFO - 'SHELL': '/bin/bash', 12:21:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YtyTLGDyri/Listeners', 12:21:35 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:21:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:35 INFO - 'USER': 'cltbld', 12:21:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:35 INFO - 'XPC_FLAGS': '0x0', 12:21:35 INFO - 'XPC_SERVICE_NAME': '0', 12:21:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:38 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:21:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:21:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:21:38 INFO - Installing collected packages: mozsystemmonitor 12:21:38 INFO - Running setup.py install for mozsystemmonitor 12:21:38 INFO - Successfully installed mozsystemmonitor 12:21:38 INFO - Cleaning up... 12:21:38 INFO - Return code: 0 12:21:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:21: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')]} 12:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21: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')]} 12:21:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21: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', '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 0x109867a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109877420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe011c9e3a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109706e00>, '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 0x10956fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109701b20>, '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.25zIUEcJsC/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.YtyTLGDyri/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:21: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:21: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 blobuploader==1.2.4 12:21:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.25zIUEcJsC/Render', 12:21:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:38 INFO - 'HOME': '/Users/cltbld', 12:21:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:38 INFO - 'LOGNAME': 'cltbld', 12:21:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:38 INFO - 'MOZ_NO_REMOTE': '1', 12:21:38 INFO - 'NO_EM_RESTART': '1', 12:21:38 INFO - 'PAGER': '/bin/cat', 12:21:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:38 INFO - 'PWD': '/builds/slave/test', 12:21:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:38 INFO - 'SHELL': '/bin/bash', 12:21:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YtyTLGDyri/Listeners', 12:21:38 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:21:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:38 INFO - 'USER': 'cltbld', 12:21:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:38 INFO - 'XPC_FLAGS': '0x0', 12:21:38 INFO - 'XPC_SERVICE_NAME': '0', 12:21:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:38 INFO - Downloading/unpacking blobuploader==1.2.4 12:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:41 INFO - Downloading blobuploader-1.2.4.tar.gz 12:21:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:21:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:21:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:42 INFO - Downloading docopt-0.6.1.tar.gz 12:21:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:21:42 INFO - Installing collected packages: blobuploader, requests, docopt 12:21:42 INFO - Running setup.py install for blobuploader 12:21:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:21:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:21:42 INFO - Running setup.py install for requests 12:21:42 INFO - Running setup.py install for docopt 12:21:43 INFO - Successfully installed blobuploader requests docopt 12:21:43 INFO - Cleaning up... 12:21:43 INFO - Return code: 0 12:21:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:21: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')]} 12:21:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21: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')]} 12:21:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:43 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 0x109867a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109877420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe011c9e3a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109706e00>, '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 0x10956fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109701b20>, '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.25zIUEcJsC/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.YtyTLGDyri/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:21:43 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 12:21:43 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 12:21:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.25zIUEcJsC/Render', 12:21:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:43 INFO - 'HOME': '/Users/cltbld', 12:21:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:43 INFO - 'LOGNAME': 'cltbld', 12:21:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:43 INFO - 'MOZ_NO_REMOTE': '1', 12:21:43 INFO - 'NO_EM_RESTART': '1', 12:21:43 INFO - 'PAGER': '/bin/cat', 12:21:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:43 INFO - 'PWD': '/builds/slave/test', 12:21:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:43 INFO - 'SHELL': '/bin/bash', 12:21:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YtyTLGDyri/Listeners', 12:21:43 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:21:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:43 INFO - 'USER': 'cltbld', 12:21:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:43 INFO - 'XPC_FLAGS': '0x0', 12:21:43 INFO - 'XPC_SERVICE_NAME': '0', 12:21:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:21:43 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-GIRe7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:21:43 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-8qPMbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:21:43 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-ZkVn1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:21:43 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-66Hr1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:21:43 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-6e7qAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:21:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-Wy1ku7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:21:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-U0HQzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:21:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-sTckPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:21:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-myIrvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:21:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-ysvKqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:21:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-W96ItZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:21:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-4Y09Ot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:21:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-6Wx4lE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:21:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-0M00on-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:21:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:21:44 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-MIHHNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:21:45 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-PXhAEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:21:45 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-YB4561-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:21:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:21:45 INFO - Running setup.py install for manifestparser 12:21:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:21:45 INFO - Running setup.py install for mozcrash 12:21:45 INFO - Running setup.py install for mozdebug 12:21:45 INFO - Running setup.py install for mozdevice 12:21:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:21:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:21:45 INFO - Running setup.py install for mozfile 12:21:46 INFO - Running setup.py install for mozhttpd 12:21:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:21:46 INFO - Running setup.py install for mozinfo 12:21:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:21:46 INFO - Running setup.py install for mozInstall 12:21:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:21:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:21:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:21:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:21:46 INFO - Running setup.py install for mozleak 12:21:46 INFO - Running setup.py install for mozlog 12:21:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:21:46 INFO - Running setup.py install for moznetwork 12:21:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:21:46 INFO - Running setup.py install for mozprocess 12:21:47 INFO - Running setup.py install for mozprofile 12:21:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:21:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:21:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:21:47 INFO - Running setup.py install for mozrunner 12:21:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:21:47 INFO - Running setup.py install for mozscreenshot 12:21:47 INFO - Running setup.py install for moztest 12:21:47 INFO - Running setup.py install for mozversion 12:21:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:21:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:21:47 INFO - Cleaning up... 12:21:47 INFO - Return code: 0 12:21:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:21:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:21:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:47 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 0x109867a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109877420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe011c9e3a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109706e00>, '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 0x10956fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109701b20>, '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.25zIUEcJsC/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.YtyTLGDyri/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:21:47 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 12:21:47 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 12:21:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.25zIUEcJsC/Render', 12:21:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:47 INFO - 'HOME': '/Users/cltbld', 12:21:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:47 INFO - 'LOGNAME': 'cltbld', 12:21:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:47 INFO - 'MOZ_NO_REMOTE': '1', 12:21:47 INFO - 'NO_EM_RESTART': '1', 12:21:47 INFO - 'PAGER': '/bin/cat', 12:21:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:47 INFO - 'PWD': '/builds/slave/test', 12:21:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:47 INFO - 'SHELL': '/bin/bash', 12:21:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YtyTLGDyri/Listeners', 12:21:47 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:21:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:47 INFO - 'USER': 'cltbld', 12:21:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:47 INFO - 'XPC_FLAGS': '0x0', 12:21:47 INFO - 'XPC_SERVICE_NAME': '0', 12:21:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:21:48 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-Xqc188-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:21:48 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)) 12:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:21:48 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-zQK5Ed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:21:48 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)) 12:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:21:48 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-LLtblV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:21:48 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)) 12:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:21:48 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-HoHU__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:21:48 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)) 12:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:21:48 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-7_4zOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:21:48 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)) 12:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:21:48 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-MounCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:21:48 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)) 12:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:21:48 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-8qWU9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:21:48 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)) 12:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:21:48 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-yJN1gg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:21:48 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)) 12:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:21:49 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-GSolRO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:21:49 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)) 12:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:21:49 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-lZhyLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:21:49 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)) 12:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:21:49 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-HDFR7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:21:49 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)) 12:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:21:49 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-h_5ZCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:21:49 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)) 12:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:21:49 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-DZal4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:21:49 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)) 12:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:21:49 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-j_Aiek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:21:49 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)) 12:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:21:49 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-DqvYF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:21:49 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)) 12:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:21:49 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-qp3IJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:21:49 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)) 12:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:21:49 INFO - Running setup.py (path:/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/pip-6eCjjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:21:49 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)) 12:21:49 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)) 12:21:49 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)) 12:21:49 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)) 12:21:49 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)) 12:21:49 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)) 12:21:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:21: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 12:21: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 12:21: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 12:21: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 12:21: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 12:21: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 12:21:52 INFO - Downloading blessings-1.6.tar.gz 12:21:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:21:52 INFO - Installing collected packages: blessings 12:21:52 INFO - Running setup.py install for blessings 12:21:52 INFO - Successfully installed blessings 12:21:52 INFO - Cleaning up... 12:21:52 INFO - Return code: 0 12:21:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:21:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:21:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:21:52 INFO - Reading from file tmpfile_stdout 12:21:52 INFO - Current package versions: 12:21:52 INFO - blessings == 1.6 12:21:52 INFO - blobuploader == 1.2.4 12:21:52 INFO - docopt == 0.6.1 12:21:52 INFO - manifestparser == 1.1 12:21:52 INFO - mozInstall == 1.12 12:21:52 INFO - mozcrash == 0.17 12:21:52 INFO - mozdebug == 0.1 12:21:52 INFO - mozdevice == 0.48 12:21:52 INFO - mozfile == 1.2 12:21:52 INFO - mozhttpd == 0.7 12:21:52 INFO - mozinfo == 0.9 12:21:52 INFO - mozleak == 0.1 12:21:52 INFO - mozlog == 3.1 12:21:52 INFO - moznetwork == 0.27 12:21:52 INFO - mozprocess == 0.22 12:21:52 INFO - mozprofile == 0.28 12:21:52 INFO - mozrunner == 6.11 12:21:52 INFO - mozscreenshot == 0.1 12:21:52 INFO - mozsystemmonitor == 0.0 12:21:52 INFO - moztest == 0.7 12:21:52 INFO - mozversion == 1.4 12:21:52 INFO - psutil == 3.1.1 12:21:52 INFO - requests == 1.2.3 12:21:52 INFO - wsgiref == 0.1.2 12:21:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:21:52 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')]} 12:21:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:52 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')]} 12:21:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:52 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 0x109867a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109877420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe011c9e3a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109706e00>, '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 0x10956fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109701b20>, '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.25zIUEcJsC/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.YtyTLGDyri/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:21:52 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 12:21:52 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 12:21:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.25zIUEcJsC/Render', 12:21:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:53 INFO - 'HOME': '/Users/cltbld', 12:21:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:53 INFO - 'LOGNAME': 'cltbld', 12:21:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:53 INFO - 'MOZ_NO_REMOTE': '1', 12:21:53 INFO - 'NO_EM_RESTART': '1', 12:21:53 INFO - 'PAGER': '/bin/cat', 12:21:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:53 INFO - 'PWD': '/builds/slave/test', 12:21:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:53 INFO - 'SHELL': '/bin/bash', 12:21:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YtyTLGDyri/Listeners', 12:21:53 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:21:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:53 INFO - 'USER': 'cltbld', 12:21:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:53 INFO - 'XPC_FLAGS': '0x0', 12:21:53 INFO - 'XPC_SERVICE_NAME': '0', 12:21:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:53 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)) 12:21:53 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)) 12:21:53 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)) 12:21:53 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)) 12:21:53 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)) 12:21:53 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)) 12:21:53 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)) 12:21:53 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)) 12:21:53 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)) 12:21:53 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)) 12:21:53 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)) 12:21:53 INFO - Cleaning up... 12:21:53 INFO - Return code: 0 12:21:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:21:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:21:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109867a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109877420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe011c9e3a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109706e00>, '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 0x10956fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109701b20>, '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.25zIUEcJsC/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.YtyTLGDyri/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:21:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:21:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:21:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.25zIUEcJsC/Render', 12:21:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:53 INFO - 'HOME': '/Users/cltbld', 12:21:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:53 INFO - 'LOGNAME': 'cltbld', 12:21:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:53 INFO - 'MOZ_NO_REMOTE': '1', 12:21:53 INFO - 'NO_EM_RESTART': '1', 12:21:53 INFO - 'PAGER': '/bin/cat', 12:21:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:53 INFO - 'PWD': '/builds/slave/test', 12:21:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:53 INFO - 'SHELL': '/bin/bash', 12:21:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YtyTLGDyri/Listeners', 12:21:53 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:21:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:53 INFO - 'USER': 'cltbld', 12:21:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:53 INFO - 'XPC_FLAGS': '0x0', 12:21:53 INFO - 'XPC_SERVICE_NAME': '0', 12:21:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:53 INFO - Downloading/unpacking jsonschema 12:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:53 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 12:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:21:56 INFO - Downloading/unpacking functools32 (from jsonschema) 12:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:56 INFO - Downloading functools32-3.2.3-2.tar.gz 12:21:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:21:56 INFO - warning: no files found matching '*.txt' 12:21:56 INFO - no previously-included directories found matching 'build' 12:21:56 INFO - no previously-included directories found matching 'dist' 12:21:56 INFO - no previously-included directories found matching '.git*' 12:21:56 INFO - Installing collected packages: jsonschema, functools32 12:21:56 INFO - Running setup.py install for functools32 12:21:56 INFO - warning: no files found matching '*.txt' 12:21:56 INFO - no previously-included directories found matching 'build' 12:21:56 INFO - no previously-included directories found matching 'dist' 12:21:56 INFO - no previously-included directories found matching '.git*' 12:21:56 INFO - Successfully installed jsonschema functools32 12:21:56 INFO - Cleaning up... 12:21:56 INFO - Return code: 0 12:21:56 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:21:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:21:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 12:21:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:21:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:21:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109867a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109877420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe011c9e3a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109706e00>, '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 0x10956fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109701b20>, '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.25zIUEcJsC/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.YtyTLGDyri/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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 12:21:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 12:21:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 12:21:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.25zIUEcJsC/Render', 12:21:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:21:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:21:56 INFO - 'HOME': '/Users/cltbld', 12:21:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:21:56 INFO - 'LOGNAME': 'cltbld', 12:21:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:21:56 INFO - 'MOZ_NO_REMOTE': '1', 12:21:56 INFO - 'NO_EM_RESTART': '1', 12:21:56 INFO - 'PAGER': '/bin/cat', 12:21:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:21:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:21:56 INFO - 'PWD': '/builds/slave/test', 12:21:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:21:56 INFO - 'SHELL': '/bin/bash', 12:21:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YtyTLGDyri/Listeners', 12:21:56 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:21:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:21:56 INFO - 'USER': 'cltbld', 12:21:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:21:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:21:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:21:56 INFO - 'XPC_FLAGS': '0x0', 12:21:56 INFO - 'XPC_SERVICE_NAME': '0', 12:21:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:21:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:21:57 INFO - Downloading/unpacking flake8 12:21: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 12:21: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 12:21: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 12:21: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 12:21: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 12:21: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 12:21:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:21:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:21:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:21:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:21:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:21:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:22:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:22:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:22:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:22:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:22:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:22:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:22:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:22:00 INFO - no previously-included directories found matching 'docs/_build' 12:22:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:22:00 INFO - Running setup.py install for pep8 12:22:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:22:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:22:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:22:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:22:01 INFO - no previously-included directories found matching 'docs/_build' 12:22:01 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:22:01 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:22:01 INFO - Cleaning up... 12:22:01 INFO - Return code: 0 12:22:01 INFO - Running post-action listener: _resource_record_post_action 12:22:01 INFO - Running post-action listener: _start_resource_monitoring 12:22:01 INFO - Starting resource monitoring. 12:22:01 INFO - ##### 12:22:01 INFO - ##### Running install step. 12:22:01 INFO - ##### 12:22:01 INFO - Running pre-action listener: _resource_record_pre_action 12:22:01 INFO - Running main action method: install 12:22:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:22:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:22:01 INFO - Reading from file tmpfile_stdout 12:22:01 INFO - Detecting whether we're running mozinstall >=1.0... 12:22:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:22:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:22:01 INFO - Reading from file tmpfile_stdout 12:22:01 INFO - Output received: 12:22:01 INFO - Usage: mozinstall [options] installer 12:22:01 INFO - Options: 12:22:01 INFO - -h, --help show this help message and exit 12:22:01 INFO - -d DEST, --destination=DEST 12:22:01 INFO - Directory to install application into. [default: 12:22:01 INFO - "/builds/slave/test"] 12:22:01 INFO - --app=APP Application being installed. [default: firefox] 12:22:01 INFO - mkdir: /builds/slave/test/build/application 12:22:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:22:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 12:22:24 INFO - Reading from file tmpfile_stdout 12:22:24 INFO - Output received: 12:22:24 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 12:22:24 INFO - Running post-action listener: _resource_record_post_action 12:22:24 INFO - ##### 12:22:24 INFO - ##### Running run-tests step. 12:22:24 INFO - ##### 12:22:24 INFO - Running pre-action listener: _resource_record_pre_action 12:22:24 INFO - Running main action method: run_tests 12:22:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:22:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:22:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:22:24 INFO - 2016-07-08 12:22:24.121 screenresolution[1647:7865] starting screenresolution argv=screenresolution get 12:22:24 INFO - 2016-07-08 12:22:24.124 screenresolution[1647:7865] Display 0: 1600x1200x32@60 12:22:24 INFO - 2016-07-08 12:22:24.130 screenresolution[1648:7868] starting screenresolution argv=screenresolution list 12:22:24 INFO - Available Modes on Display 0 12:22:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:22:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:22:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:22:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:22:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:22:24 INFO - Intel Iris: 12:22:24 INFO - Chipset Model: Intel Iris 12:22:24 INFO - Type: GPU 12:22:24 INFO - Bus: Built-In 12:22:24 INFO - VRAM (Dynamic, Max): 1536 MB 12:22:24 INFO - Vendor: Intel (0x8086) 12:22:24 INFO - Device ID: 0x0a2e 12:22:24 INFO - Revision ID: 0x0009 12:22:24 INFO - Displays: 12:22:24 INFO - Display: 12:22:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:22:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:22:24 INFO - Display Serial Number: 1600x1200 60 12:22:24 INFO - Main Display: Yes 12:22:24 INFO - Mirror: Off 12:22:24 INFO - Online: Yes 12:22:24 INFO - Rotation: Supported 12:22:24 INFO - Return code: 0 12:22:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:22:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:22:24 INFO - Python 2.7.3 12:22:24 INFO - Return code: 0 12:22:24 INFO - grabbing minidump binary from tooltool 12:22:24 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')]} 12:22:24 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 0x109706e00>, '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 0x10956fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109701b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:22:24 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 12:22:24 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 12:22:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:22:24 INFO - Return code: 0 12:22:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:22:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:22:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:22:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:22:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:22:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:22:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:22:25 INFO - Return code: 0 12:22:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0210', '--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 12:22:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0210 --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 12:22:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.25zIUEcJsC/Render', 12:22:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:22:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:22:25 INFO - 'HOME': '/Users/cltbld', 12:22:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:22:25 INFO - 'LOGNAME': 'cltbld', 12:22:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:22:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:22:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:22:25 INFO - 'MOZ_NO_REMOTE': '1', 12:22:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:22:25 INFO - 'NO_EM_RESTART': '1', 12:22:25 INFO - 'PAGER': '/bin/cat', 12:22:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:22:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:22:25 INFO - 'PWD': '/builds/slave/test', 12:22:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:22:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:22:25 INFO - 'SHELL': '/bin/bash', 12:22:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YtyTLGDyri/Listeners', 12:22:25 INFO - 'TMPDIR': '/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/', 12:22:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:22:25 INFO - 'USER': 'cltbld', 12:22:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:22:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:22:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:22:25 INFO - 'XPC_FLAGS': '0x0', 12:22:25 INFO - 'XPC_SERVICE_NAME': '0', 12:22:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:22:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0210', '--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 12:22:25 INFO - mozversion application_buildid: 20160708040727 12:22:25 INFO - mozversion application_changeset: 24e82cb41040b1f515135ae3ba8b6bddf234d509 12:22:25 INFO - mozversion application_display_name: Firefox 12:22:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:22:25 INFO - mozversion application_name: Firefox 12:22:25 INFO - mozversion application_remotingname: firefox 12:22:25 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:22:25 INFO - mozversion application_vendor: Mozilla 12:22:25 INFO - mozversion application_version: 48.0 12:22:25 INFO - mozversion platform_buildid: 20160708040727 12:22:25 INFO - mozversion platform_changeset: 24e82cb41040b1f515135ae3ba8b6bddf234d509 12:22:25 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:22:25 INFO - mozversion platform_version: 48.0 12:22:25 INFO - using testdate: 1468005745 12:22:25 INFO - actual date: 1468005745 12:22:25 INFO - starting webserver on 'localhost:49275' 12:22:25 INFO - SUITE-START | Running 2 tests 12:22:25 INFO - TEST-START | dromaeo_css 12:22:25 INFO - operating with platform_type : mac_ 12:22:25 INFO - Initialising browser for dromaeo_css test... 12:22:25 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmp2QsLIV/profile http://localhost:49275/getInfo.html) 12:22:28 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 12:22:28 INFO - PROCESS | 1666 | colorDepth:24 12:22:28 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 12:22:28 INFO - PROCESS | 1666 | __metrics 12:22:28 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 12:22:29 INFO - TEST-INFO | 1666: exit 0 12:22:29 INFO - Browser initialized. 12:22:29 INFO - Running cycle 1/1 for dromaeo_css test... 12:22:29 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmp2QsLIV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:22:41 INFO - PROCESS | 1671 | RSS: Main: 170991616 12:22:41 INFO - PROCESS | 1671 | 12:23:30 INFO - PROCESS | 1671 | 1468005810833 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] 12:25:41 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 12:25:42 INFO - PROCESS | 1671 | RSS: Main: 184655872 12:25:42 INFO - PROCESS | 1671 | 12:28:44 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 12:28:45 INFO - PROCESS | 1671 | RSS: Main: 194375680 12:28:45 INFO - PROCESS | 1671 | 12:31:46 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 12:31:47 INFO - PROCESS | 1671 | RSS: Main: 196509696 12:31:47 INFO - PROCESS | 1671 | 12:34:49 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 12:34:49 INFO - PROCESS | 1671 | RSS: Main: 200044544 12:34:49 INFO - PROCESS | 1671 | 12:37:52 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 12:37:53 INFO - PROCESS | 1671 | RSS: Main: 199294976 12:37:53 INFO - PROCESS | 1671 | 12:40:55 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 12:40:55 INFO - PROCESS | 1671 | RSS: Main: 204910592 12:40:55 INFO - PROCESS | 1671 | 12:40:55 INFO - PROCESS | 1671 | __start_tp_report 12:40:55 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 12:40:55 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 12:40:55 INFO - PROCESS | 1671 | |i|pagename|runs| 12:40:55 INFO - PROCESS | 1671 | |0;dojo.html;3014.9698503014965;3651.5070465487156;3696.1868388954435;3751.6998640108795;3761.92476150477;5975.521958243341;6006.6396016239005;6026.819195424145;6028.125921741351;6048.788292409767;5919.289685237767;6017.338092809799;6049.4555490006005;6050.969745151279;6057.39426057394;4315.2883650205285;5341.866453338668;5359.839204823861;5433.836984890439;5558.249636299089;5336.8132115375965;5449.128139497681;5454.563634909197;5461.644993075437;5473.370562385317;5462.644928079682;5485.670859748408;5488.945110548883;5489.368722596892;5508.807191748289;5409.83770486886;5432.103702889019;5453.209284653703;5477.863053423656;5494.450554944514;5304.893902121936;5362.705051222181;5413.918791218135;5456.945430545723;5462.754176062087;6021.307549631804;6051.606645568068;6061.363556826558;6062.8787424251705;6084.391560843925;6202.968985155046;6214.533909956726;6330.113784070234;6334.113224148623;6339;6342.809715708536;6417.133686952248;6428.260750013715;6436.8390790230615;6462.967685161591;6112.358202388774;6132.662703551303;6139.4781443577795;6146.416090471398;6158.5381096417495;6432.71052802625;6488.805335839932;6623.768168114093;6664.400203981665;6671.232808227116;6200.441960223511;6303.873922521524;6303.905441418382;6309.138804052815;6316.147320111818;6367.044943258548;6394.23269207698;6418.101465794793;6431.196100487439;6448.032795080775;6213.409726076015;6259;6288.465480434123;6291.559590828598;6323.715432805535;6283.654399008098;6304.432601065926;6331.461825744863;6333.841653958688;6350.523710721714;5095.8980820383385;5161.458046905096;5169.896602068013;5176;5180.093483640348;4467.441569803775;4536.296873984537;4562.612177964844;4563.95436045642;4619.953800461953;5045.646804723634;6140.109684095781;6227.408396202353;6241.500679945593;6261.217347831521;6392.328805475358;6407.807765767035;6414.967925160345;6420.26166990802;6423.839404014843;6106.72519736613;6154.107654390178;6198.101275315054;6249.593776404519;6250.49996000328;4627.814887404466;4727.905441891212;5361.892762144736;5651.660900345993;5683.0338842396895;6243.594166379171;6543.869122617426;6733.360330768569;6776.76281330161;6790.5246632736225;6337.302896681454;6383.36166383358;6431.935680643133;6437.871242575028;6442.420182183626;5481.753321100639;6755.831104222434;6777.186737591521;6786.457083433214;6794.184697836291;6210.5652604317265;6263.6241825490615;6265.498760099273;6367.586106903036;6380.617162970237;6322.956712142445;6385.648789316632;6396.584222025554;6412.35876412355;6428.260750013808;6209.59637623553;6242.937570624417;6270.623762574261;6295.18162638854;6297.716602752978;6160.229971253593;6178.598391104564;6232.065190221503;6288.717007734755;6298.307186209605;6117.0824376342125;6236.563440559117;6237.095621134819;6284.874302513833;6318.526110541636;6276.278228003838;6277.74889004453;6281.96859015702;6282.968585157045;6290.49676025926;6141.025769127022;6186.443220110212;6220.7511699531515;6246.843828904313;6323.336049714898 12:40:55 INFO - PROCESS | 1671 | |1;ext.html;539.9136138217884;564.2861779848492;567.3862045136299;593.5666963116925;611.1199872184058;31200.687993120064;36218.818905905464;39842.601573984255;39867;39942.2011559769;40470;41155.9711007225;41178.17643647133;41265;41316.58683413165;9672.274579406556;9767.511624418787;9802.313838031341;9852.211823054175;9856.556454959544;8148.511089334629;9660.130588247057;9713.660021899235;9787.217022638208;9849.55676994537;9731.805363892718;9766.511674416286;9811.705648830546;9865.112139907407;9886.011398860128;9481.952590237004;9846.60613575456;9855.655052073178;9930.10629043386;9934.105930466292;8869.556522173865;9879.950600247024;9888.208943284591;9900;9909.950450247774;41077.38383924244;41354;41713.374299385534;41924.58075419237;41996.37005444921;3286.063471910494;3325.434676105068;3346.849391777376;3471.826408679581;3693.796841173761;28405.58552523038;28653;29104;29724.70275297241;30344.54483182754;2462.7414121517163;2533.7964466878084;2620.135355332736;2790.0513825299495;2986.1340211338684;303416;379841;384843;387466.0626696877;389171;67756;69066.309336907;69535.65232173807;70114;70176;68422.63154736877;73397.63301183567;80936.59531702421;82591.5870420644;82846;19045.523861903286;19060.80939190618;19251.51871203231;19288.324908628412;19319.130639121275;14284.642883927778;14308.427662893368;14733.55799326022;14750.114993100447;14842.406303747945;21442.892785535973;21538.78461215368;21797.455063623536;21820.672689909665;21914.561708765734;8027.919720802834;8853.937527496613;9106.81786364269;9288.814223715488;9292.72121836346;996.5366104761321;1019.1133713669155;1055.2613170780485;1192.7375977284983;1229.1948773553336;30864.228394290323;30961.606727697308;30987.84506077455;31439.84280078585;31532.684673152973;1034.4310629153936;1160.9187356884936;1221.7922953097816;1234.2532767675427;1248.4382028087398;15631.218439078228;15916.522504324888;16019.919900400424;16091.678166436606;16146.273417695997;648053;649099;655566;658399;658727;63889;64823.35176648267;64962.076706548316;64994.350056499774;65046.349536504975;1552.7903732996126;1569.9764503532456;1576.2355257700005;1588.9126098064717;1605.0851014921616;26454.07410740692;28702;30512;30793.384132317675;31153.221169470944;54496;55685;56017;56140.43859561515;56159.71920140373;478787;486729;491299;559599;559674;36661.45007824938;38846.41730374103;44659.33011004903;44669.77665111654;46221;43728.68813935587;44001.77999109984;44607.55392446164;45054.77472612616;45500.08999819919;37756.81121594484;37782.81108594439;37842.43236351402;38860.02849928774;39205.01987450336;38084;38302.61697382991;38365.424518632804;38506.229875401776;38902.4164637525;36463.45304820483;37763.62236377601;38114.80942595269;38566;38918.221635567694;33173.17067073246;33306.33387332288;33626.66373336235;34073.48889766705;34351.48472772961;25350.619740703536;25675;25740.742592573835;26070.217893463225;26154.607680885183 12:40:55 INFO - PROCESS | 1671 | |2;jquery.html;407.527268368692;408.33033824527797;410.2492438836929;416.35049323056035;419.8362638570958;15876.841231587681;20264.29074982374;20295.695564566526;20738.688919666198;21120.57758844826;20993.47516312093;21301.786982130212;21441.785582144174;22021;22061.669074963847;5987.431194036571;6012.5791194616395;6214.968925155368;6216.471599914013;6234.688265586725;5909.556783241253;6185.103160041791;6217;6221;6242.344553821851;5946.791862284821;6132.570720049598;6212.534059945499;6220.502359811227;7043.295670432967;6893.345132212432;6939.167299924042;6955.269696681877;6965.442764578846;6994.440444764457;5718.79984200551;6013.098035294741;6032;6153.661548614825;6221.751129954796;11356.318620882774;13702.10936289138;13901.235432051233;14069.788953165711;14141.575752727435;2177.390330707389;2222.1333679864865;2272.8863556822257;2332.043862016565;2720.319920019995;9989.051040151175;10130.746731331701;10195.133413659847;10253.23100767438;10464.21518386124;1834.7064469684785;1838.09933132764;1858.479625704805;1873.7095750158746;1878.3801345555935;106649;124861;130804;131720.34139829336;132904;11672.299662020307;11868.465919033664;11898.643040708792;12179.695507612381;12205.633830984907;15457;15462.536123916301;15498.985131932777;15747.842521574637;15837.683246335238;9347.859782103274;9349.252059835197;9426.764330891645;9502.334836561513;9596.9040309596;9378.812423751488;9391.718248452557;9522.285828562886;9569.521523923777;9628.614855405705;4001.1797581495725;4809.182438985311;4843.917115640308;4879.39007624047;4957.851264462072;2022.4438279472968;2081.261152290915;2089.7178880851193;2093.72781538399;2120.724305840262;942.7972985808084;973.6981535723806;984.2913102566141;991.6529214774771;1060.3372891942536;20152;20633.793662063188;20672.069756860976;20839;20983.790162098183;985.5417230987625;995.8804943406693;1013.3058854684565;1058.5183741397645;1101.2896681640457;8226;8650.09174036717;10932.890671093346;11097.056750176163;11311.886881131248;572118;622434;630029;649115;652236;106846;116874.24688629678;127214;130827.34586327008;139020;1003.5233264199593;1030.1449796668896;1032.396048311746;1037.5123691864812;1038.5430410619304;15229.314680839167;18234.632402570125;18900.621987560447;19048.52378690544;19132.80867191366;51777.482225177264;53048;54297;54708;55020.174697380375;72366.63816680883;74921;75082;77818;78260;8355.373346998878;8384;8422.663093476192;8552.315814734682;8596.656133754579;9389.624415023596;12384.81422778641;12928.741425171633;13222.735545288848;13294.26881521487;10321.535530901278;11375.43122843871;11639.127065469955;11711.004564612103;11805.76388472243;10908.727281818017;11158.330500169692;11168.497417616065;11414.201005929504;11438.313701177956;11496;11593.362365070003;11723.882761172279;11758;12002.159848810848;8128.593570321342;8180.181981801772;8291.543965081979;8395.622197001028;8487.575621219037;10955.34267943894;11251.043661288883;11453.770924581295;11461.083113351078;11562.653120406401 12:40:55 INFO - PROCESS | 1671 | |3;mootools.html;1652.4051341517052;1677.0273241519915;1947.2405761752918;1986.7516560429938;2079.604875073737;8305.95847020764;9020.188183063523;9265.907340926597;9459.385139965898;9473.857892131615;9044.954775226131;9090.818183636324;9160.954195229031;9183.586738596763;9198;2559.756823101802;2569.6530968319316;2580.9225723228333;2587.1979686297213;2593.7795287400595;2501.16211069292;2531.1014589820625;2564.3076369380256;2565.7947364210913;2569.4475687727136;2536.9746302537014;2542.5931850903858;2544.7964162866924;2552;2586.9482610347877;2535.9239222823253;2541.885615147323;2562.231330600822;2585.2631999879945;2587.430765231646;2207.845450818443;2544.7964162866924;2568.0883286433236;2573.5110329037425;2583.935401614956;9295.2099071579;9320.021397753206;9415.964243933162;9438.764030899214;9451.763705907339;5732.77068917247;5801.042827933428;5906.438888305604;5924.526037916957;5946.108083787423;3294.7858389204625;3729.9440508392395;3799.2971300309255;3823.0824602095577;3882.592327805596;912.2838571721167;1067.4929408530963;1095.058249905081;1095.7370231144548;1096.4846522134585;12267.141300108906;12316.938415307866;12320.260784352879;12323.815142772866;12764.553240636626;4979.502049794992;5014.197728363517;5118.61610379223;5186.429492755794;5191;10502.894971050344;10624.256302058782;10667.83998240027;10731.731706707395;10745.194110441747;5609.663420194802;5654.773809047674;5655.717214139277;5660.349059858087;5665.688387138664;4691.741954192552;5127.256547800623;5150.690958542499;5263.868403289948;5314.0700377433795;3560.857565697395;4224.260754367973;4243.27864263076;4269.210196113667;4286.078493123993;1328.4885319152197;1406.9648258793611;1426.3510102903153;1437.5687293811814;1441.7981482592445;917.1333090229803;917.8623206519076;964.6912987843823;978.9412635241766;1020.2756043209256;4768.7615619218905;6108.511319094462;6130.264368275835;6279.623222606659;6286.339934306923;919.7215869940787;966.3042609321277;978.6463555149876;991.306085739985;1065.573770491794;3443.362977849106;4060.4924384451942;4117.691173161965;4155.293600087993;4182.205380977604;10859.728506787393;11190.04884584803;11239;11367.545298188144;11515.827262591069;9347.579358928864;10744.516496757664;11086.279391839505;11215.775684486427;11218.943905280421;8337.082920878698;8502.022267439324;8612.956935215283;8668.349873759367;8714.302855771522;5918.526517878646;5941.9405805943115;6156.753729850756;6219.253689557282;6221.782237621662;10043.899561004297;10105.292629515863;10283.485825708534;10349.172066234532;10350.378977261389;10125.18998480105;10160.491975401348;10315.845262321222;10356.119729823049;10416;7014.228434872262;7754.961225193838;8299.211574900375;8555.572221388782;8641.56792160402;8481.40630155883;8621.698240561802;8657.09100544459;8685.261752751087;8713.390062695804;7591;7709.768706938801;7781.26078022587;7819.921800781919;7829.960850195713;7535.510191837513;7546.547207167588;7567.281108294703;7610.391168706601;7699.076110866507;7237.710491580277;7325.743598974011;7347.191808901123;7375.668094935633;7389.704411823467;5001.399832020181;5062.898742025212;5124.56441202502;5135.87160320995;5142.100132476727;5189.714565698771;5274.498922602346;5323.281357016908;5352;5396.271503346998 12:40:55 INFO - PROCESS | 1671 | |4;prototype.html;319.53507646374527;333.7513552403459;341.09609534732954;341.60544571020466;344.105326152005;5912.822615321536;6398.29618741938;6864.279250678682;6899.793006209816;6923.792286231408;6961;6968.651567421634;6990.196127445345;7006.369426751591;7015.894761578567;4142.461480007595;5079.3142925705115;5112.36095488064;5117.206832940881;5120.948790512084;5098.821541246057;5111.309973153632;5125.410577783547;5126.154184559543;5133.589312854962;4006.258842114218;4891.804327826901;5035.748212589374;5060.215666571669;5106.66806657566;4132.822145688479;4769.332293478916;4963.7766300516205;5032.446430892617;5086.41506226785;4166.66666666666;4175.958240417587;4181.728187667823;4185.623293903533;4193.622573968327;6552.443042341359;6577.473802095821;6598;6603.273639899608;6605.339466053404;4838.766114640779;5853.560982926298;5903.852403689899;5920.407959204045;5935;7368.336849683555;7374.188839227681;7388.113426388814;7390.667419966061;7411.7035318587195;3883.2233553289457;4557.658175636808;4691.695039822401;4756.120117778188;4774;5886.941130588682;6224.28832071184;7777;7857.489263197931;7889.408294377888;4178.373244013423;4310.52584215736;4412.426384569985;4546.545345465427;4814.422269327703;4480.596746292849;4866.926996095061;4897.853064408074;4911.287863259808;4924.285978533093;5210.583153347723;5531.197976293495;5914.763409463573;5915.556333275022;5920.911186332209;5945.791897283661;6928.787462194196;7114.004823223379;7406.592637404994;7437.590932498764;1674.112720258265;1679.529731675133;1694.4916525042438;1697.176869218428;1718.3298513579714;994.8706668133096;1047.8218702820539;1049.506731836036;1075.8977897099762;1081.34578579958;6985.825354366079;7137.250588688113;7144.857102858018;7159.176694680074;7221.855562888818;7775.183605721431;7776.377889768805;7798.5710785906185;7809.843803123906;7816.41376896735;7202.315780000891;7207.89188162178;7214.603196824225;7219.530730502501;7248.601326926964;3733.981330093332;3951.4665520154413;4082.7346222495444;4112.259793237186;4147.668186545129;6643.933560664428;8177.9591102044105;8258.710945116893;8265.256126948603;8293.046299675614;6529;7938.89619250899;7996.9200307997335;8025.23760242776;8053.798655033554;8230.135835737161;8231.09457959624;8237.258646721704;8251.051129120107;8261.876071858927;7469.029026226556;7568.318851303409;7727.11138219112;7750.806229844299;7814.788707750307;7579.052618422697;7627.5042122261875;7646.388288936761;7674.53952762836;7705.152433232228;8261.215184557457;8263;8275.62759675828;8295.253427191581;8306.750797476085;4839.661223714447;4899.902001959869;4903.16646170159;4904.117258893433;4907.386576677915;7479.626018698935;7722.768316950501;7735.497192682457;7758.340541053848;7782.844343112993;6864;7439;7510.699572017058;7512.76039453484;7529.284717951786;6797.660116994031;7461.515001524884;7466.178720340867;7468.477206595486;7492.625368731433;6196.566240363131;6819.386255237053;7295.52579082358;7312.561246325237;7320.377767889789;7387.815304617213;7399.371053460516;7408.740694075682;7440.4419668524;7507.66215520292;7001.719931202695;7084.964575177091;7215;7235.384992275505;7236.855262894818 12:40:55 INFO - PROCESS | 1671 | |5;yui.html;702.1433850702142;702.4380495603517;783.7962129846238;789.7078081109988;797.7526966640341;15148.166850823214;15472;15475.30361133749;15479.993800402966;15486;11813.2321399109;14393.136411815298;14399.28003599821;14403;14415.351309191085;638.5338702746998;773.3078894389517;933.3606479561505;958.1233171647968;962.6486332488627;934.7102398256562;944.7779771753633;954.4607296877242;961.8124465729186;968.2786324188489;961.9278554108434;964.7973925475659;964.9228061755077;998.6155330197889;1005.7334806276306;967.5936106835146;970.917472014876;976.1458723616835;985.6747273399761;997.9151772099344;638.7572722365471;675.9459243260527;742.6695120671303;753.2768542199495;754.5321900421719;14991.700165996619;15252.923735381362;15272.465463708828;15359.15524646144;15361.923190384088;12970.416331265116;13029.15310504814;13160.802587961092;13188.208707477583;13226.537071202461;10082.49587520621;10169.847452288222;10223.386596804141;10250.97490250969;10313.226508011854;3420.452727563603;3447.3622379859808;3546.3616549021167;3604.7476676632646;3688.575813781396;79391;80781;82076.58961705153;82120;82405.58797206036;9639;9702.27232957517;9748.025197480196;9918.25613079022;9929;22074.779252207274;22112;22174.556508869733;22175.66736498954;22196.334109976728;7401;7491.288327608869;7522.247775222434;7539.962300188574;7542.622868856535;3991.4212439196735;4019.537753158424;4076.4700588923843;4108.897277568025;4117.053077792125;3741.3639681254253;3792.488014118059;3793.6585707286476;3795.2978698940783;3798.1074447504816;672.1530871102447;681.6625770243761;697.6581475077176;704.7251571886968;712.6436781609195;507.68777202020493;544.4065968094795;598.4075764992713;606.9423404776539;642.4282388674084;8038.397120216007;8058.758237252891;8143.104258531557;8211.7947051323;8465.238128568459;537.5941164420838;605.7274226598048;616.4051690119056;621.6829416997274;626.0765371077624;3877.6101219305187;3923.7449565778134;3959.742616729903;3980.8606698765984;3982.104026594051;70078;87371.56314218388;90599.54700226588;91024.63463048059;91294;79452;80047;80204.59897700475;81065;81068.59465702633;51926;57311.71344143336;69799;69910.60178796395;71891.2810871895;6033.487153591818;7811.843763124819;7822.06135263772;7853.450258481851;7868.6459109339075;38888.80555597204;49478.75260623818;50068.749656251486;50612.493875060776;50838.98322033613;50255;64420;68606;68837.31162688509;69872.65063674649;10118.949405252926;10358.015988481082;10362.274640775073;10367.63713270032;10402.791944160923;10697.839532407175;10847.891521084688;11344.716382090513;11403.372814495282;11486.483108259981;10256.282060255711;10307.381557106597;10358.119559837498;10369.74075648115;10413.895861041292;9674.274429417681;9741.853872191781;9757.26820488452;9780.60877564918;9799.412035277906;10132.696019119438;10168;10195.235357348376;10215.693529194135;10484.318519296221;8669.87442089114;9130.315226357918;9134.543272836465;9757.12185903272;9890.406575605486;9436.575354109218;9776.706698798764;9820.050389874099;9903.900960990586;9922.950385248028 12:40:55 INFO - PROCESS | 1671 | __end_tp_report 12:40:55 INFO - PROCESS | 1671 | __start_cc_report 12:40:55 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,400 12:40:55 INFO - PROCESS | 1671 | __end_cc_report 12:40:55 INFO - PROCESS | 1671 | __startTimestamp1468006855323__endTimestamp 12:40:55 INFO - PROCESS | 1671 | 12:40:55 INFO - PROCESS | 1671 | ------- Summary: start ------- 12:40:55 INFO - PROCESS | 1671 | Number of tests: 6 12:40:55 INFO - PROCESS | 1671 | 12:40:55 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5949.36 Median:6220.75 stddev:671.67 (10.8%) stddev-sans-first:633.06 12:40:55 INFO - PROCESS | 1671 | Values: 3015.0 3651.5 3696.2 3751.7 3761.9 5975.5 6006.6 6026.8 6028.1 6048.8 5919.3 6017.3 6049.5 6051.0 6057.4 4315.3 5341.9 5359.8 5433.8 5558.2 5336.8 5449.1 5454.6 5461.6 5473.4 5462.6 5485.7 5488.9 5489.4 5508.8 5409.8 5432.1 5453.2 5477.9 5494.5 5304.9 5362.7 5413.9 5456.9 5462.8 6021.3 6051.6 6061.4 6062.9 6084.4 6203.0 6214.5 6330.1 6334.1 6339.0 6342.8 6417.1 6428.3 6436.8 6463.0 6112.4 6132.7 6139.5 6146.4 6158.5 6432.7 6488.8 6623.8 6664.4 6671.2 6200.4 6303.9 6303.9 6309.1 6316.1 6367.0 6394.2 6418.1 6431.2 6448.0 6213.4 6259.0 6288.5 6291.6 6323.7 6283.7 6304.4 6331.5 6333.8 6350.5 5095.9 5161.5 5169.9 5176.0 5180.1 4467.4 4536.3 4562.6 4564.0 4620.0 5045.6 6140.1 6227.4 6241.5 6261.2 6392.3 6407.8 6415.0 6420.3 6423.8 6106.7 6154.1 6198.1 6249.6 6250.5 4627.8 4727.9 5361.9 5651.7 5683.0 6243.6 6543.9 6733.4 6776.8 6790.5 6337.3 6383.4 6431.9 6437.9 6442.4 5481.8 6755.8 6777.2 6786.5 6794.2 6210.6 6263.6 6265.5 6367.6 6380.6 6323.0 6385.6 6396.6 6412.4 6428.3 6209.6 6242.9 6270.6 6295.2 6297.7 6160.2 6178.6 6232.1 6288.7 6298.3 6117.1 6236.6 6237.1 6284.9 6318.5 6276.3 6277.7 6282.0 6283.0 6290.5 6141.0 6186.4 6220.8 6246.8 6323.3 12:40:55 INFO - PROCESS | 1671 | 12:40:55 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:67110.60 Median:214044.61 stddev:140538.99 (65.7%) stddev-sans-first:140844.36 12:40:55 INFO - PROCESS | 1671 | Values: 539.9 564.3 567.4 593.6 611.1 31200.7 36218.8 39842.6 39867.0 39942.2 40470.0 41156.0 41178.2 41265.0 41316.6 9672.3 9767.5 9802.3 9852.2 9856.6 8148.5 9660.1 9713.7 9787.2 9849.6 9731.8 9766.5 9811.7 9865.1 9886.0 9482.0 9846.6 9855.7 9930.1 9934.1 8869.6 9880.0 9888.2 9900.0 9910.0 41077.4 41354.0 41713.4 41924.6 41996.4 3286.1 3325.4 3346.8 3471.8 3693.8 28405.6 28653.0 29104.0 29724.7 30344.5 2462.7 2533.8 2620.1 2790.1 2986.1 303416.0 379841.0 384843.0 387466.1 389171.0 67756.0 69066.3 69535.7 70114.0 70176.0 68422.6 73397.6 80936.6 82591.6 82846.0 19045.5 19060.8 19251.5 19288.3 19319.1 14284.6 14308.4 14733.6 14750.1 14842.4 21442.9 21538.8 21797.5 21820.7 21914.6 8027.9 8853.9 9106.8 9288.8 9292.7 996.5 1019.1 1055.3 1192.7 1229.2 30864.2 30961.6 30987.8 31439.8 31532.7 1034.4 1160.9 1221.8 1234.3 1248.4 15631.2 15916.5 16019.9 16091.7 16146.3 648053.0 649099.0 655566.0 658399.0 658727.0 63889.0 64823.4 64962.1 64994.4 65046.3 1552.8 1570.0 1576.2 1588.9 1605.1 26454.1 28702.0 30512.0 30793.4 31153.2 54496.0 55685.0 56017.0 56140.4 56159.7 478787.0 486729.0 491299.0 559599.0 559674.0 36661.5 38846.4 44659.3 44669.8 46221.0 43728.7 44001.8 44607.6 45054.8 45500.1 37756.8 37782.8 37842.4 38860.0 39205.0 38084.0 38302.6 38365.4 38506.2 38902.4 36463.5 37763.6 38114.8 38566.0 38918.2 33173.2 33306.3 33626.7 34073.5 34351.5 25350.6 25675.0 25740.7 26070.2 26154.6 12:40:55 INFO - PROCESS | 1671 | 12:40:55 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:35695.40 Median:11711.26 stddev:104296.25 (890.6%) stddev-sans-first:104555.17 12:40:55 INFO - PROCESS | 1671 | Values: 407.5 408.3 410.2 416.4 419.8 15876.8 20264.3 20295.7 20738.7 21120.6 20993.5 21301.8 21441.8 22021.0 22061.7 5987.4 6012.6 6215.0 6216.5 6234.7 5909.6 6185.1 6217.0 6221.0 6242.3 5946.8 6132.6 6212.5 6220.5 7043.3 6893.3 6939.2 6955.3 6965.4 6994.4 5718.8 6013.1 6032.0 6153.7 6221.8 11356.3 13702.1 13901.2 14069.8 14141.6 2177.4 2222.1 2272.9 2332.0 2720.3 9989.1 10130.7 10195.1 10253.2 10464.2 1834.7 1838.1 1858.5 1873.7 1878.4 106649.0 124861.0 130804.0 131720.3 132904.0 11672.3 11868.5 11898.6 12179.7 12205.6 15457.0 15462.5 15499.0 15747.8 15837.7 9347.9 9349.3 9426.8 9502.3 9596.9 9378.8 9391.7 9522.3 9569.5 9628.6 4001.2 4809.2 4843.9 4879.4 4957.9 2022.4 2081.3 2089.7 2093.7 2120.7 942.8 973.7 984.3 991.7 1060.3 20152.0 20633.8 20672.1 20839.0 20983.8 985.5 995.9 1013.3 1058.5 1101.3 8226.0 8650.1 10932.9 11097.1 11311.9 572118.0 622434.0 630029.0 649115.0 652236.0 106846.0 116874.2 127214.0 130827.3 139020.0 1003.5 1030.1 1032.4 1037.5 1038.5 15229.3 18234.6 18900.6 19048.5 19132.8 51777.5 53048.0 54297.0 54708.0 55020.2 72366.6 74921.0 75082.0 77818.0 78260.0 8355.4 8384.0 8422.7 8552.3 8596.7 9389.6 12384.8 12928.7 13222.7 13294.3 10321.5 11375.4 11639.1 11711.0 11805.8 10908.7 11158.3 11168.5 11414.2 11438.3 11496.0 11593.4 11723.9 11758.0 12002.2 8128.6 8180.2 8291.5 8395.6 8487.6 10955.3 11251.0 11453.8 11461.1 11562.7 12:40:55 INFO - PROCESS | 1671 | 12:40:55 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:6000.32 Median:5090.76 stddev:3322.01 (65.3%) stddev-sans-first:3315.26 12:40:55 INFO - PROCESS | 1671 | Values: 1652.4 1677.0 1947.2 1986.8 2079.6 8306.0 9020.2 9265.9 9459.4 9473.9 9045.0 9090.8 9161.0 9183.6 9198.0 2559.8 2569.7 2580.9 2587.2 2593.8 2501.2 2531.1 2564.3 2565.8 2569.4 2537.0 2542.6 2544.8 2552.0 2586.9 2535.9 2541.9 2562.2 2585.3 2587.4 2207.8 2544.8 2568.1 2573.5 2583.9 9295.2 9320.0 9416.0 9438.8 9451.8 5732.8 5801.0 5906.4 5924.5 5946.1 3294.8 3729.9 3799.3 3823.1 3882.6 912.3 1067.5 1095.1 1095.7 1096.5 12267.1 12316.9 12320.3 12323.8 12764.6 4979.5 5014.2 5118.6 5186.4 5191.0 10502.9 10624.3 10667.8 10731.7 10745.2 5609.7 5654.8 5655.7 5660.3 5665.7 4691.7 5127.3 5150.7 5263.9 5314.1 3560.9 4224.3 4243.3 4269.2 4286.1 1328.5 1407.0 1426.4 1437.6 1441.8 917.1 917.9 964.7 978.9 1020.3 4768.8 6108.5 6130.3 6279.6 6286.3 919.7 966.3 978.6 991.3 1065.6 3443.4 4060.5 4117.7 4155.3 4182.2 10859.7 11190.0 11239.0 11367.5 11515.8 9347.6 10744.5 11086.3 11215.8 11218.9 8337.1 8502.0 8613.0 8668.3 8714.3 5918.5 5941.9 6156.8 6219.3 6221.8 10043.9 10105.3 10283.5 10349.2 10350.4 10125.2 10160.5 10315.8 10356.1 10416.0 7014.2 7755.0 8299.2 8555.6 8641.6 8481.4 8621.7 8657.1 8685.3 8713.4 7591.0 7709.8 7781.3 7819.9 7830.0 7535.5 7546.5 7567.3 7610.4 7699.1 7237.7 7325.7 7347.2 7375.7 7389.7 5001.4 5062.9 5124.6 5135.9 5142.1 5189.7 5274.5 5323.3 5352.0 5396.3 12:40:55 INFO - PROCESS | 1671 | 12:40:55 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:6006.79 Median:6882.04 stddev:1998.01 (29.0%) stddev-sans-first:1957.48 12:40:55 INFO - PROCESS | 1671 | Values: 319.5 333.8 341.1 341.6 344.1 5912.8 6398.3 6864.3 6899.8 6923.8 6961.0 6968.7 6990.2 7006.4 7015.9 4142.5 5079.3 5112.4 5117.2 5120.9 5098.8 5111.3 5125.4 5126.2 5133.6 4006.3 4891.8 5035.7 5060.2 5106.7 4132.8 4769.3 4963.8 5032.4 5086.4 4166.7 4176.0 4181.7 4185.6 4193.6 6552.4 6577.5 6598.0 6603.3 6605.3 4838.8 5853.6 5903.9 5920.4 5935.0 7368.3 7374.2 7388.1 7390.7 7411.7 3883.2 4557.7 4691.7 4756.1 4774.0 5886.9 6224.3 7777.0 7857.5 7889.4 4178.4 4310.5 4412.4 4546.5 4814.4 4480.6 4866.9 4897.9 4911.3 4924.3 5210.6 5531.2 5914.8 5915.6 5920.9 5945.8 6928.8 7114.0 7406.6 7437.6 1674.1 1679.5 1694.5 1697.2 1718.3 994.9 1047.8 1049.5 1075.9 1081.3 6985.8 7137.3 7144.9 7159.2 7221.9 7775.2 7776.4 7798.6 7809.8 7816.4 7202.3 7207.9 7214.6 7219.5 7248.6 3734.0 3951.5 4082.7 4112.3 4147.7 6643.9 8178.0 8258.7 8265.3 8293.0 6529.0 7938.9 7996.9 8025.2 8053.8 8230.1 8231.1 8237.3 8251.1 8261.9 7469.0 7568.3 7727.1 7750.8 7814.8 7579.1 7627.5 7646.4 7674.5 7705.2 8261.2 8263.0 8275.6 8295.3 8306.8 4839.7 4899.9 4903.2 4904.1 4907.4 7479.6 7722.8 7735.5 7758.3 7782.8 6864.0 7439.0 7510.7 7512.8 7529.3 6797.7 7461.5 7466.2 7468.5 7492.6 6196.6 6819.4 7295.5 7312.6 7320.4 7387.8 7399.4 7408.7 7440.4 7507.7 7001.7 7085.0 7215.0 7235.4 7236.9 12:40:55 INFO - PROCESS | 1671 | 12:40:55 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:17795.57 Median:640.59 stddev:24985.87 (3900.4%) stddev-sans-first:25022.99 12:40:55 INFO - PROCESS | 1671 | Values: 702.1 702.4 783.8 789.7 797.8 15148.2 15472.0 15475.3 15480.0 15486.0 11813.2 14393.1 14399.3 14403.0 14415.4 638.5 773.3 933.4 958.1 962.6 934.7 944.8 954.5 961.8 968.3 961.9 964.8 964.9 998.6 1005.7 967.6 970.9 976.1 985.7 997.9 638.8 675.9 742.7 753.3 754.5 14991.7 15252.9 15272.5 15359.2 15361.9 12970.4 13029.2 13160.8 13188.2 13226.5 10082.5 10169.8 10223.4 10251.0 10313.2 3420.5 3447.4 3546.4 3604.7 3688.6 79391.0 80781.0 82076.6 82120.0 82405.6 9639.0 9702.3 9748.0 9918.3 9929.0 22074.8 22112.0 22174.6 22175.7 22196.3 7401.0 7491.3 7522.2 7540.0 7542.6 3991.4 4019.5 4076.5 4108.9 4117.1 3741.4 3792.5 3793.7 3795.3 3798.1 672.2 681.7 697.7 704.7 712.6 507.7 544.4 598.4 606.9 642.4 8038.4 8058.8 8143.1 8211.8 8465.2 537.6 605.7 616.4 621.7 626.1 3877.6 3923.7 3959.7 3980.9 3982.1 70078.0 87371.6 90599.5 91024.6 91294.0 79452.0 80047.0 80204.6 81065.0 81068.6 51926.0 57311.7 69799.0 69910.6 71891.3 6033.5 7811.8 7822.1 7853.5 7868.6 38888.8 49478.8 50068.7 50612.5 50839.0 50255.0 64420.0 68606.0 68837.3 69872.7 10118.9 10358.0 10362.3 10367.6 10402.8 10697.8 10847.9 11344.7 11403.4 11486.5 10256.3 10307.4 10358.1 10369.7 10413.9 9674.3 9741.9 9757.3 9780.6 9799.4 10132.7 10168.0 10195.2 10215.7 10484.3 8669.9 9130.3 9134.5 9757.1 9890.4 9436.6 9776.7 9820.1 9903.9 9923.0 12:40:55 INFO - PROCESS | 1671 | -------- Summary: end -------- 12:40:55 INFO - PROCESS | 1671 | 12:40:55 INFO - TEST-INFO | 1671: exit 0 12:40:55 INFO - TEST-OK | dromaeo_css | took 1110075ms 12:40:55 INFO - TEST-START | kraken 12:40:55 INFO - operating with platform_type : mac_ 12:40:55 INFO - Initialising browser for kraken test... 12:40:55 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmpeGvJ2l/profile http://localhost:49275/getInfo.html) 12:40:58 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 12:40:58 INFO - PROCESS | 1679 | colorDepth:24 12:40:58 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 12:40:58 INFO - PROCESS | 1679 | __metrics 12:40:58 ERROR - PROCESS | 1679 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 12:40:58 INFO - TEST-INFO | 1679: exit 0 12:40:58 INFO - Browser initialized. 12:40:58 INFO - Running cycle 1/1 for kraken test... 12:40:58 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/tmpeGvJ2l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:41:10 INFO - PROCESS | 1683 | RSS: Main: 171130880 12:41:10 INFO - PROCESS | 1683 | 12:41:13 INFO - PROCESS | 1683 | 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) 12:41:14 INFO - PROCESS | 1683 | RSS: Main: 183717888 12:41:14 INFO - PROCESS | 1683 | 12:41:17 INFO - PROCESS | 1683 | 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) 12:41:17 INFO - PROCESS | 1683 | RSS: Main: 192425984 12:41:17 INFO - PROCESS | 1683 | 12:41:20 INFO - PROCESS | 1683 | 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) 12:41:21 INFO - PROCESS | 1683 | RSS: Main: 195919872 12:41:21 INFO - PROCESS | 1683 | 12:41:23 INFO - PROCESS | 1683 | 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) 12:41:24 INFO - PROCESS | 1683 | RSS: Main: 197226496 12:41:24 INFO - PROCESS | 1683 | 12:41:26 INFO - PROCESS | 1683 | 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) 12:41:26 INFO - PROCESS | 1683 | RSS: Main: 197881856 12:41:26 INFO - PROCESS | 1683 | 12:41:29 INFO - PROCESS | 1683 | 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) 12:41:30 INFO - PROCESS | 1683 | RSS: Main: 198139904 12:41:30 INFO - PROCESS | 1683 | 12:41:33 INFO - PROCESS | 1683 | 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) 12:41:33 INFO - PROCESS | 1683 | RSS: Main: 197885952 12:41:33 INFO - PROCESS | 1683 | 12:41:36 INFO - PROCESS | 1683 | 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) 12:41:37 INFO - PROCESS | 1683 | RSS: Main: 198479872 12:41:37 INFO - PROCESS | 1683 | 12:41:39 INFO - PROCESS | 1683 | 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) 12:41:39 INFO - PROCESS | 1683 | RSS: Main: 197836800 12:41:39 INFO - PROCESS | 1683 | 12:41:41 INFO - PROCESS | 1683 | 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) 12:41:42 INFO - PROCESS | 1683 | RSS: Main: 197902336 12:41:42 INFO - PROCESS | 1683 | 12:41:44 INFO - PROCESS | 1683 | 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) 12:41:45 INFO - PROCESS | 1683 | RSS: Main: 198021120 12:41:45 INFO - PROCESS | 1683 | 12:41:47 INFO - PROCESS | 1683 | 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) 12:41:48 INFO - PROCESS | 1683 | RSS: Main: 198090752 12:41:48 INFO - PROCESS | 1683 | 12:41:51 INFO - PROCESS | 1683 | 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) 12:41:51 INFO - PROCESS | 1683 | RSS: Main: 198057984 12:41:51 INFO - PROCESS | 1683 | 12:41:53 INFO - PROCESS | 1683 | 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) 12:41:53 INFO - PROCESS | 1683 | RSS: Main: 200024064 12:41:53 INFO - PROCESS | 1683 | 12:41:53 INFO - PROCESS | 1683 | __start_tp_report 12:41:53 INFO - PROCESS | 1683 | _x_x_mozilla_page_load 12:41:53 INFO - PROCESS | 1683 | _x_x_mozilla_page_load_details 12:41:53 INFO - PROCESS | 1683 | |i|pagename|runs| 12:41:53 INFO - PROCESS | 1683 | |0;ai-astar;113;119;108;115;118;117;104;115;115;115 12:41:53 INFO - PROCESS | 1683 | |1;audio-beat-detection;152;129;181;190;144;139;121;152;150;119 12:41:53 INFO - PROCESS | 1683 | |2;audio-dft;194;198;198;198;194;195;195;196;191;193 12:41:53 INFO - PROCESS | 1683 | |3;audio-fft;64;75;78;143;57;67;95;94;152;59 12:41:53 INFO - PROCESS | 1683 | |4;audio-oscillator;80;81;124;76;80;83;119;80;81;119 12:41:53 INFO - PROCESS | 1683 | |5;imaging-gaussian-blur;92;89;96;131;92;88;94;94;121;89 12:41:53 INFO - PROCESS | 1683 | |6;imaging-darkroom;98;96;97;97;103;152;97;121;103;96 12:41:53 INFO - PROCESS | 1683 | |7;imaging-desaturate;90;92;126;140;109;146;84;85;136;142 12:41:53 INFO - PROCESS | 1683 | |8;json-parse-financial;59;60;64;63;63;62;69;107;100;91 12:41:53 INFO - PROCESS | 1683 | |9;json-stringify-tinderbox;63;64;68;66;79;69;67;66;66;66 12:41:53 INFO - PROCESS | 1683 | |10;stanford-crypto-aes;82;80;81;79;82;80;82;79;82;110 12:41:53 INFO - PROCESS | 1683 | |11;stanford-crypto-ccm;130;125;127;125;125;126;125;126;142;138 12:41:53 INFO - PROCESS | 1683 | |12;stanford-crypto-pbkdf2;164;169;173;218;211;168;165;165;164;163 12:41:53 INFO - PROCESS | 1683 | |13;stanford-crypto-sha256-iterative;65;69;69;66;69;64;64;69;66;67 12:41:53 INFO - PROCESS | 1683 | __end_tp_report 12:41:53 INFO - PROCESS | 1683 | __start_cc_report 12:41:53 INFO - PROCESS | 1683 | _x_x_mozilla_cycle_collect,1043 12:41:53 INFO - PROCESS | 1683 | __end_cc_report 12:41:53 INFO - PROCESS | 1683 | __startTimestamp1468006913763__endTimestamp 12:41:53 INFO - PROCESS | 1683 | 12:41:53 INFO - PROCESS | 1683 | ------- Summary: start ------- 12:41:53 INFO - PROCESS | 1683 | Number of tests: 14 12:41:53 INFO - PROCESS | 1683 | 12:41:53 INFO - PROCESS | 1683 | [#0] ai-astar Cycles:10 Average:113.90 Median:115.00 stddev:4.61 (4.0%) stddev-sans-first:4.87 12:41:53 INFO - PROCESS | 1683 | Values: 113.0 119.0 108.0 115.0 118.0 117.0 104.0 115.0 115.0 115.0 12:41:53 INFO - PROCESS | 1683 | 12:41:53 INFO - PROCESS | 1683 | [#1] audio-beat-detection Cycles:10 Average:147.70 Median:151.00 stddev:23.37 (15.5%) stddev-sans-first:24.74 12:41:53 INFO - PROCESS | 1683 | Values: 152.0 129.0 181.0 190.0 144.0 139.0 121.0 152.0 150.0 119.0 12:41:53 INFO - PROCESS | 1683 | 12:41:53 INFO - PROCESS | 1683 | [#2] audio-dft Cycles:10 Average:195.20 Median:195.50 stddev:2.35 (1.2%) stddev-sans-first:2.45 12:41:53 INFO - PROCESS | 1683 | Values: 194.0 198.0 198.0 198.0 194.0 195.0 195.0 196.0 191.0 193.0 12:41:53 INFO - PROCESS | 1683 | 12:41:53 INFO - PROCESS | 1683 | [#3] audio-fft Cycles:10 Average:88.40 Median:71.00 stddev:33.82 (47.6%) stddev-sans-first:34.70 12:41:53 INFO - PROCESS | 1683 | Values: 64.0 75.0 78.0 143.0 57.0 67.0 95.0 94.0 152.0 59.0 12:41:53 INFO - PROCESS | 1683 | 12:41:53 INFO - PROCESS | 1683 | [#4] audio-oscillator Cycles:10 Average:92.30 Median:80.00 stddev:19.70 (24.6%) stddev-sans-first:20.38 12:41:53 INFO - PROCESS | 1683 | Values: 80.0 81.0 124.0 76.0 80.0 83.0 119.0 80.0 81.0 119.0 12:41:53 INFO - PROCESS | 1683 | 12:41:53 INFO - PROCESS | 1683 | [#5] imaging-gaussian-blur Cycles:10 Average:98.60 Median:92.00 stddev:14.85 (16.1%) stddev-sans-first:15.56 12:41:53 INFO - PROCESS | 1683 | Values: 92.0 89.0 96.0 131.0 92.0 88.0 94.0 94.0 121.0 89.0 12:41:53 INFO - PROCESS | 1683 | 12:41:53 INFO - PROCESS | 1683 | [#6] imaging-darkroom Cycles:10 Average:106.00 Median:96.50 stddev:17.85 (18.5%) stddev-sans-first:18.69 12:41:53 INFO - PROCESS | 1683 | Values: 98.0 96.0 97.0 97.0 103.0 152.0 97.0 121.0 103.0 96.0 12:41:53 INFO - PROCESS | 1683 | 12:41:53 INFO - PROCESS | 1683 | [#7] imaging-desaturate Cycles:10 Average:115.00 Median:115.00 stddev:25.66 (22.3%) stddev-sans-first:25.58 12:41:53 INFO - PROCESS | 1683 | Values: 90.0 92.0 126.0 140.0 109.0 146.0 84.0 85.0 136.0 142.0 12:41:53 INFO - PROCESS | 1683 | 12:41:53 INFO - PROCESS | 1683 | [#8] json-parse-financial Cycles:10 Average:73.80 Median:63.00 stddev:18.21 (28.9%) stddev-sans-first:18.51 12:41:53 INFO - PROCESS | 1683 | Values: 59.0 60.0 64.0 63.0 63.0 62.0 69.0 107.0 100.0 91.0 12:41:53 INFO - PROCESS | 1683 | 12:41:53 INFO - PROCESS | 1683 | [#9] json-stringify-tinderbox Cycles:10 Average:67.40 Median:66.50 stddev:4.43 (6.7%) stddev-sans-first:4.40 12:41:53 INFO - PROCESS | 1683 | Values: 63.0 64.0 68.0 66.0 79.0 69.0 67.0 66.0 66.0 66.0 12:41:53 INFO - PROCESS | 1683 | 12:41:53 INFO - PROCESS | 1683 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:81.50 stddev:9.32 (11.4%) stddev-sans-first:9.87 12:41:53 INFO - PROCESS | 1683 | Values: 82.0 80.0 81.0 79.0 82.0 80.0 82.0 79.0 82.0 110.0 12:41:53 INFO - PROCESS | 1683 | 12:41:53 INFO - PROCESS | 1683 | [#11] stanford-crypto-ccm Cycles:10 Average:128.90 Median:126.50 stddev:6.12 (4.8%) stddev-sans-first:6.48 12:41:53 INFO - PROCESS | 1683 | Values: 130.0 125.0 127.0 125.0 125.0 126.0 125.0 126.0 142.0 138.0 12:41:53 INFO - PROCESS | 1683 | 12:41:53 INFO - PROCESS | 1683 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.00 Median:168.50 stddev:20.58 (12.2%) stddev-sans-first:21.36 12:41:53 INFO - PROCESS | 1683 | Values: 164.0 169.0 173.0 218.0 211.0 168.0 165.0 165.0 164.0 163.0 12:41:53 INFO - PROCESS | 1683 | 12:41:53 INFO - PROCESS | 1683 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:68.00 stddev:2.10 (3.1%) stddev-sans-first:2.12 12:41:53 INFO - PROCESS | 1683 | Values: 65.0 69.0 69.0 66.0 69.0 64.0 64.0 69.0 66.0 67.0 12:41:53 INFO - PROCESS | 1683 | -------- Summary: end -------- 12:41:53 INFO - PROCESS | 1683 | 12:41:54 INFO - TEST-INFO | 1683: exit 0 12:41:54 INFO - TEST-OK | kraken | took 58625ms 12:41:54 INFO - SUITE-END | took 1168s 12:41:54 INFO - Completed test suite (00:19:29) 12:41:54 INFO - javascript benchmark 12:41:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3014.9698503014965, 3651.5070465487156, 3696.1868388954435, 3751.6998640108795, 3761.92476150477, 5975.521958243341, 6006.6396016239005, 6026.819195424145, 6028.125921741351, 6048.788292409767, 5919.289685237767, 6017.338092809799, 6049.4555490006005, 6050.969745151279, 6057.39426057394, 4315.2883650205285, 5341.866453338668, 5359.839204823861, 5433.836984890439, 5558.249636299089, 5336.8132115375965, 5449.128139497681, 5454.563634909197, 5461.644993075437, 5473.370562385317, 5462.644928079682, 5485.670859748408, 5488.945110548883, 5489.368722596892, 5508.807191748289, 5409.83770486886, 5432.103702889019, 5453.209284653703, 5477.863053423656, 5494.450554944514, 5304.893902121936, 5362.705051222181, 5413.918791218135, 5456.945430545723, 5462.754176062087, 6021.307549631804, 6051.606645568068, 6061.363556826558, 6062.8787424251705, 6084.391560843925, 6202.968985155046, 6214.533909956726, 6330.113784070234, 6334.113224148623, 6339.0, 6342.809715708536, 6417.133686952248, 6428.260750013715, 6436.8390790230615, 6462.967685161591, 6112.358202388774, 6132.662703551303, 6139.4781443577795, 6146.416090471398, 6158.5381096417495, 6432.71052802625, 6488.805335839932, 6623.768168114093, 6664.400203981665, 6671.232808227116, 6200.441960223511, 6303.873922521524, 6303.905441418382, 6309.138804052815, 6316.147320111818, 6367.044943258548, 6394.23269207698, 6418.101465794793, 6431.196100487439, 6448.032795080775, 6213.409726076015, 6259.0, 6288.465480434123, 6291.559590828598, 6323.715432805535, 6283.654399008098, 6304.432601065926, 6331.461825744863, 6333.841653958688, 6350.523710721714, 5095.8980820383385, 5161.458046905096, 5169.896602068013, 5176.0, 5180.093483640348, 4467.441569803775, 4536.296873984537, 4562.612177964844, 4563.95436045642, 4619.953800461953, 5045.646804723634, 6140.109684095781, 6227.408396202353, 6241.500679945593, 6261.217347831521, 6392.328805475358, 6407.807765767035, 6414.967925160345, 6420.26166990802, 6423.839404014843, 6106.72519736613, 6154.107654390178, 6198.101275315054, 6249.593776404519, 6250.49996000328, 4627.814887404466, 4727.905441891212, 5361.892762144736, 5651.660900345993, 5683.0338842396895, 6243.594166379171, 6543.869122617426, 6733.360330768569, 6776.76281330161, 6790.5246632736225, 6337.302896681454, 6383.36166383358, 6431.935680643133, 6437.871242575028, 6442.420182183626, 5481.753321100639, 6755.831104222434, 6777.186737591521, 6786.457083433214, 6794.184697836291, 6210.5652604317265, 6263.6241825490615, 6265.498760099273, 6367.586106903036, 6380.617162970237, 6322.956712142445, 6385.648789316632, 6396.584222025554, 6412.35876412355, 6428.260750013808, 6209.59637623553, 6242.937570624417, 6270.623762574261, 6295.18162638854, 6297.716602752978, 6160.229971253593, 6178.598391104564, 6232.065190221503, 6288.717007734755, 6298.307186209605, 6117.0824376342125, 6236.563440559117, 6237.095621134819, 6284.874302513833, 6318.526110541636, 6276.278228003838, 6277.74889004453, 6281.96859015702, 6282.968585157045, 6290.49676025926, 6141.025769127022, 6186.443220110212, 6220.7511699531515, 6246.843828904313, 6323.336049714898], "value": 5907.948148281771, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [539.9136138217884, 564.2861779848492, 567.3862045136299, 593.5666963116925, 611.1199872184058, 31200.687993120064, 36218.818905905464, 39842.601573984255, 39867.0, 39942.2011559769, 40470.0, 41155.9711007225, 41178.17643647133, 41265.0, 41316.58683413165, 9672.274579406556, 9767.511624418787, 9802.313838031341, 9852.211823054175, 9856.556454959544, 8148.511089334629, 9660.130588247057, 9713.660021899235, 9787.217022638208, 9849.55676994537, 9731.805363892718, 9766.511674416286, 9811.705648830546, 9865.112139907407, 9886.011398860128, 9481.952590237004, 9846.60613575456, 9855.655052073178, 9930.10629043386, 9934.105930466292, 8869.556522173865, 9879.950600247024, 9888.208943284591, 9900.0, 9909.950450247774, 41077.38383924244, 41354.0, 41713.374299385534, 41924.58075419237, 41996.37005444921, 3286.063471910494, 3325.434676105068, 3346.849391777376, 3471.826408679581, 3693.796841173761, 28405.58552523038, 28653.0, 29104.0, 29724.70275297241, 30344.54483182754, 2462.7414121517163, 2533.7964466878084, 2620.135355332736, 2790.0513825299495, 2986.1340211338684, 303416.0, 379841.0, 384843.0, 387466.0626696877, 389171.0, 67756.0, 69066.309336907, 69535.65232173807, 70114.0, 70176.0, 68422.63154736877, 73397.63301183567, 80936.59531702421, 82591.5870420644, 82846.0, 19045.523861903286, 19060.80939190618, 19251.51871203231, 19288.324908628412, 19319.130639121275, 14284.642883927778, 14308.427662893368, 14733.55799326022, 14750.114993100447, 14842.406303747945, 21442.892785535973, 21538.78461215368, 21797.455063623536, 21820.672689909665, 21914.561708765734, 8027.919720802834, 8853.937527496613, 9106.81786364269, 9288.814223715488, 9292.72121836346, 996.5366104761321, 1019.1133713669155, 1055.2613170780485, 1192.7375977284983, 1229.1948773553336, 30864.228394290323, 30961.606727697308, 30987.84506077455, 31439.84280078585, 31532.684673152973, 1034.4310629153936, 1160.9187356884936, 1221.7922953097816, 1234.2532767675427, 1248.4382028087398, 15631.218439078228, 15916.522504324888, 16019.919900400424, 16091.678166436606, 16146.273417695997, 648053.0, 649099.0, 655566.0, 658399.0, 658727.0, 63889.0, 64823.35176648267, 64962.076706548316, 64994.350056499774, 65046.349536504975, 1552.7903732996126, 1569.9764503532456, 1576.2355257700005, 1588.9126098064717, 1605.0851014921616, 26454.07410740692, 28702.0, 30512.0, 30793.384132317675, 31153.221169470944, 54496.0, 55685.0, 56017.0, 56140.43859561515, 56159.71920140373, 478787.0, 486729.0, 491299.0, 559599.0, 559674.0, 36661.45007824938, 38846.41730374103, 44659.33011004903, 44669.77665111654, 46221.0, 43728.68813935587, 44001.77999109984, 44607.55392446164, 45054.77472612616, 45500.08999819919, 37756.81121594484, 37782.81108594439, 37842.43236351402, 38860.02849928774, 39205.01987450336, 38084.0, 38302.61697382991, 38365.424518632804, 38506.229875401776, 38902.4164637525, 36463.45304820483, 37763.62236377601, 38114.80942595269, 38566.0, 38918.221635567694, 33173.17067073246, 33306.33387332288, 33626.66373336235, 34073.48889766705, 34351.48472772961, 25350.619740703536, 25675.0, 25740.742592573835, 26070.217893463225, 26154.607680885183], "value": 20847.939744925916, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.527268368692, 408.33033824527797, 410.2492438836929, 416.35049323056035, 419.8362638570958, 15876.841231587681, 20264.29074982374, 20295.695564566526, 20738.688919666198, 21120.57758844826, 20993.47516312093, 21301.786982130212, 21441.785582144174, 22021.0, 22061.669074963847, 5987.431194036571, 6012.5791194616395, 6214.968925155368, 6216.471599914013, 6234.688265586725, 5909.556783241253, 6185.103160041791, 6217.0, 6221.0, 6242.344553821851, 5946.791862284821, 6132.570720049598, 6212.534059945499, 6220.502359811227, 7043.295670432967, 6893.345132212432, 6939.167299924042, 6955.269696681877, 6965.442764578846, 6994.440444764457, 5718.79984200551, 6013.098035294741, 6032.0, 6153.661548614825, 6221.751129954796, 11356.318620882774, 13702.10936289138, 13901.235432051233, 14069.788953165711, 14141.575752727435, 2177.390330707389, 2222.1333679864865, 2272.8863556822257, 2332.043862016565, 2720.319920019995, 9989.051040151175, 10130.746731331701, 10195.133413659847, 10253.23100767438, 10464.21518386124, 1834.7064469684785, 1838.09933132764, 1858.479625704805, 1873.7095750158746, 1878.3801345555935, 106649.0, 124861.0, 130804.0, 131720.34139829336, 132904.0, 11672.299662020307, 11868.465919033664, 11898.643040708792, 12179.695507612381, 12205.633830984907, 15457.0, 15462.536123916301, 15498.985131932777, 15747.842521574637, 15837.683246335238, 9347.859782103274, 9349.252059835197, 9426.764330891645, 9502.334836561513, 9596.9040309596, 9378.812423751488, 9391.718248452557, 9522.285828562886, 9569.521523923777, 9628.614855405705, 4001.1797581495725, 4809.182438985311, 4843.917115640308, 4879.39007624047, 4957.851264462072, 2022.4438279472968, 2081.261152290915, 2089.7178880851193, 2093.72781538399, 2120.724305840262, 942.7972985808084, 973.6981535723806, 984.2913102566141, 991.6529214774771, 1060.3372891942536, 20152.0, 20633.793662063188, 20672.069756860976, 20839.0, 20983.790162098183, 985.5417230987625, 995.8804943406693, 1013.3058854684565, 1058.5183741397645, 1101.2896681640457, 8226.0, 8650.09174036717, 10932.890671093346, 11097.056750176163, 11311.886881131248, 572118.0, 622434.0, 630029.0, 649115.0, 652236.0, 106846.0, 116874.24688629678, 127214.0, 130827.34586327008, 139020.0, 1003.5233264199593, 1030.1449796668896, 1032.396048311746, 1037.5123691864812, 1038.5430410619304, 15229.314680839167, 18234.632402570125, 18900.621987560447, 19048.52378690544, 19132.80867191366, 51777.482225177264, 53048.0, 54297.0, 54708.0, 55020.174697380375, 72366.63816680883, 74921.0, 75082.0, 77818.0, 78260.0, 8355.373346998878, 8384.0, 8422.663093476192, 8552.315814734682, 8596.656133754579, 9389.624415023596, 12384.81422778641, 12928.741425171633, 13222.735545288848, 13294.26881521487, 10321.535530901278, 11375.43122843871, 11639.127065469955, 11711.004564612103, 11805.76388472243, 10908.727281818017, 11158.330500169692, 11168.497417616065, 11414.201005929504, 11438.313701177956, 11496.0, 11593.362365070003, 11723.882761172279, 11758.0, 12002.159848810848, 8128.593570321342, 8180.181981801772, 8291.543965081979, 8395.622197001028, 8487.575621219037, 10955.34267943894, 11251.043661288883, 11453.770924581295, 11461.083113351078, 11562.653120406401], "value": 9742.163148733318, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1652.4051341517052, 1677.0273241519915, 1947.2405761752918, 1986.7516560429938, 2079.604875073737, 8305.95847020764, 9020.188183063523, 9265.907340926597, 9459.385139965898, 9473.857892131615, 9044.954775226131, 9090.818183636324, 9160.954195229031, 9183.586738596763, 9198.0, 2559.756823101802, 2569.6530968319316, 2580.9225723228333, 2587.1979686297213, 2593.7795287400595, 2501.16211069292, 2531.1014589820625, 2564.3076369380256, 2565.7947364210913, 2569.4475687727136, 2536.9746302537014, 2542.5931850903858, 2544.7964162866924, 2552.0, 2586.9482610347877, 2535.9239222823253, 2541.885615147323, 2562.231330600822, 2585.2631999879945, 2587.430765231646, 2207.845450818443, 2544.7964162866924, 2568.0883286433236, 2573.5110329037425, 2583.935401614956, 9295.2099071579, 9320.021397753206, 9415.964243933162, 9438.764030899214, 9451.763705907339, 5732.77068917247, 5801.042827933428, 5906.438888305604, 5924.526037916957, 5946.108083787423, 3294.7858389204625, 3729.9440508392395, 3799.2971300309255, 3823.0824602095577, 3882.592327805596, 912.2838571721167, 1067.4929408530963, 1095.058249905081, 1095.7370231144548, 1096.4846522134585, 12267.141300108906, 12316.938415307866, 12320.260784352879, 12323.815142772866, 12764.553240636626, 4979.502049794992, 5014.197728363517, 5118.61610379223, 5186.429492755794, 5191.0, 10502.894971050344, 10624.256302058782, 10667.83998240027, 10731.731706707395, 10745.194110441747, 5609.663420194802, 5654.773809047674, 5655.717214139277, 5660.349059858087, 5665.688387138664, 4691.741954192552, 5127.256547800623, 5150.690958542499, 5263.868403289948, 5314.0700377433795, 3560.857565697395, 4224.260754367973, 4243.27864263076, 4269.210196113667, 4286.078493123993, 1328.4885319152197, 1406.9648258793611, 1426.3510102903153, 1437.5687293811814, 1441.7981482592445, 917.1333090229803, 917.8623206519076, 964.6912987843823, 978.9412635241766, 1020.2756043209256, 4768.7615619218905, 6108.511319094462, 6130.264368275835, 6279.623222606659, 6286.339934306923, 919.7215869940787, 966.3042609321277, 978.6463555149876, 991.306085739985, 1065.573770491794, 3443.362977849106, 4060.4924384451942, 4117.691173161965, 4155.293600087993, 4182.205380977604, 10859.728506787393, 11190.04884584803, 11239.0, 11367.545298188144, 11515.827262591069, 9347.579358928864, 10744.516496757664, 11086.279391839505, 11215.775684486427, 11218.943905280421, 8337.082920878698, 8502.022267439324, 8612.956935215283, 8668.349873759367, 8714.302855771522, 5918.526517878646, 5941.9405805943115, 6156.753729850756, 6219.253689557282, 6221.782237621662, 10043.899561004297, 10105.292629515863, 10283.485825708534, 10349.172066234532, 10350.378977261389, 10125.18998480105, 10160.491975401348, 10315.845262321222, 10356.119729823049, 10416.0, 7014.228434872262, 7754.961225193838, 8299.211574900375, 8555.572221388782, 8641.56792160402, 8481.40630155883, 8621.698240561802, 8657.09100544459, 8685.261752751087, 8713.390062695804, 7591.0, 7709.768706938801, 7781.26078022587, 7819.921800781919, 7829.960850195713, 7535.510191837513, 7546.547207167588, 7567.281108294703, 7610.391168706601, 7699.076110866507, 7237.710491580277, 7325.743598974011, 7347.191808901123, 7375.668094935633, 7389.704411823467, 5001.399832020181, 5062.898742025212, 5124.56441202502, 5135.87160320995, 5142.100132476727, 5189.714565698771, 5274.498922602346, 5323.281357016908, 5352.0, 5396.271503346998], "value": 4849.0786162428585, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.53507646374527, 333.7513552403459, 341.09609534732954, 341.60544571020466, 344.105326152005, 5912.822615321536, 6398.29618741938, 6864.279250678682, 6899.793006209816, 6923.792286231408, 6961.0, 6968.651567421634, 6990.196127445345, 7006.369426751591, 7015.894761578567, 4142.461480007595, 5079.3142925705115, 5112.36095488064, 5117.206832940881, 5120.948790512084, 5098.821541246057, 5111.309973153632, 5125.410577783547, 5126.154184559543, 5133.589312854962, 4006.258842114218, 4891.804327826901, 5035.748212589374, 5060.215666571669, 5106.66806657566, 4132.822145688479, 4769.332293478916, 4963.7766300516205, 5032.446430892617, 5086.41506226785, 4166.66666666666, 4175.958240417587, 4181.728187667823, 4185.623293903533, 4193.622573968327, 6552.443042341359, 6577.473802095821, 6598.0, 6603.273639899608, 6605.339466053404, 4838.766114640779, 5853.560982926298, 5903.852403689899, 5920.407959204045, 5935.0, 7368.336849683555, 7374.188839227681, 7388.113426388814, 7390.667419966061, 7411.7035318587195, 3883.2233553289457, 4557.658175636808, 4691.695039822401, 4756.120117778188, 4774.0, 5886.941130588682, 6224.28832071184, 7777.0, 7857.489263197931, 7889.408294377888, 4178.373244013423, 4310.52584215736, 4412.426384569985, 4546.545345465427, 4814.422269327703, 4480.596746292849, 4866.926996095061, 4897.853064408074, 4911.287863259808, 4924.285978533093, 5210.583153347723, 5531.197976293495, 5914.763409463573, 5915.556333275022, 5920.911186332209, 5945.791897283661, 6928.787462194196, 7114.004823223379, 7406.592637404994, 7437.590932498764, 1674.112720258265, 1679.529731675133, 1694.4916525042438, 1697.176869218428, 1718.3298513579714, 994.8706668133096, 1047.8218702820539, 1049.506731836036, 1075.8977897099762, 1081.34578579958, 6985.825354366079, 7137.250588688113, 7144.857102858018, 7159.176694680074, 7221.855562888818, 7775.183605721431, 7776.377889768805, 7798.5710785906185, 7809.843803123906, 7816.41376896735, 7202.315780000891, 7207.89188162178, 7214.603196824225, 7219.530730502501, 7248.601326926964, 3733.981330093332, 3951.4665520154413, 4082.7346222495444, 4112.259793237186, 4147.668186545129, 6643.933560664428, 8177.9591102044105, 8258.710945116893, 8265.256126948603, 8293.046299675614, 6529.0, 7938.89619250899, 7996.9200307997335, 8025.23760242776, 8053.798655033554, 8230.135835737161, 8231.09457959624, 8237.258646721704, 8251.051129120107, 8261.876071858927, 7469.029026226556, 7568.318851303409, 7727.11138219112, 7750.806229844299, 7814.788707750307, 7579.052618422697, 7627.5042122261875, 7646.388288936761, 7674.53952762836, 7705.152433232228, 8261.215184557457, 8263.0, 8275.62759675828, 8295.253427191581, 8306.750797476085, 4839.661223714447, 4899.902001959869, 4903.16646170159, 4904.117258893433, 4907.386576677915, 7479.626018698935, 7722.768316950501, 7735.497192682457, 7758.340541053848, 7782.844343112993, 6864.0, 7439.0, 7510.699572017058, 7512.76039453484, 7529.284717951786, 6797.660116994031, 7461.515001524884, 7466.178720340867, 7468.477206595486, 7492.625368731433, 6196.566240363131, 6819.386255237053, 7295.52579082358, 7312.561246325237, 7320.377767889789, 7387.815304617213, 7399.371053460516, 7408.740694075682, 7440.4419668524, 7507.66215520292, 7001.719931202695, 7084.964575177091, 7215.0, 7235.384992275505, 7236.855262894818], "value": 5336.715662252242, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.1433850702142, 702.4380495603517, 783.7962129846238, 789.7078081109988, 797.7526966640341, 15148.166850823214, 15472.0, 15475.30361133749, 15479.993800402966, 15486.0, 11813.2321399109, 14393.136411815298, 14399.28003599821, 14403.0, 14415.351309191085, 638.5338702746998, 773.3078894389517, 933.3606479561505, 958.1233171647968, 962.6486332488627, 934.7102398256562, 944.7779771753633, 954.4607296877242, 961.8124465729186, 968.2786324188489, 961.9278554108434, 964.7973925475659, 964.9228061755077, 998.6155330197889, 1005.7334806276306, 967.5936106835146, 970.917472014876, 976.1458723616835, 985.6747273399761, 997.9151772099344, 638.7572722365471, 675.9459243260527, 742.6695120671303, 753.2768542199495, 754.5321900421719, 14991.700165996619, 15252.923735381362, 15272.465463708828, 15359.15524646144, 15361.923190384088, 12970.416331265116, 13029.15310504814, 13160.802587961092, 13188.208707477583, 13226.537071202461, 10082.49587520621, 10169.847452288222, 10223.386596804141, 10250.97490250969, 10313.226508011854, 3420.452727563603, 3447.3622379859808, 3546.3616549021167, 3604.7476676632646, 3688.575813781396, 79391.0, 80781.0, 82076.58961705153, 82120.0, 82405.58797206036, 9639.0, 9702.27232957517, 9748.025197480196, 9918.25613079022, 9929.0, 22074.779252207274, 22112.0, 22174.556508869733, 22175.66736498954, 22196.334109976728, 7401.0, 7491.288327608869, 7522.247775222434, 7539.962300188574, 7542.622868856535, 3991.4212439196735, 4019.537753158424, 4076.4700588923843, 4108.897277568025, 4117.053077792125, 3741.3639681254253, 3792.488014118059, 3793.6585707286476, 3795.2978698940783, 3798.1074447504816, 672.1530871102447, 681.6625770243761, 697.6581475077176, 704.7251571886968, 712.6436781609195, 507.68777202020493, 544.4065968094795, 598.4075764992713, 606.9423404776539, 642.4282388674084, 8038.397120216007, 8058.758237252891, 8143.104258531557, 8211.7947051323, 8465.238128568459, 537.5941164420838, 605.7274226598048, 616.4051690119056, 621.6829416997274, 626.0765371077624, 3877.6101219305187, 3923.7449565778134, 3959.742616729903, 3980.8606698765984, 3982.104026594051, 70078.0, 87371.56314218388, 90599.54700226588, 91024.63463048059, 91294.0, 79452.0, 80047.0, 80204.59897700475, 81065.0, 81068.59465702633, 51926.0, 57311.71344143336, 69799.0, 69910.60178796395, 71891.2810871895, 6033.487153591818, 7811.843763124819, 7822.06135263772, 7853.450258481851, 7868.6459109339075, 38888.80555597204, 49478.75260623818, 50068.749656251486, 50612.493875060776, 50838.98322033613, 50255.0, 64420.0, 68606.0, 68837.31162688509, 69872.65063674649, 10118.949405252926, 10358.015988481082, 10362.274640775073, 10367.63713270032, 10402.791944160923, 10697.839532407175, 10847.891521084688, 11344.716382090513, 11403.372814495282, 11486.483108259981, 10256.282060255711, 10307.381557106597, 10358.119559837498, 10369.74075648115, 10413.895861041292, 9674.274429417681, 9741.853872191781, 9757.26820488452, 9780.60877564918, 9799.412035277906, 10132.696019119438, 10168.0, 10195.235357348376, 10215.693529194135, 10484.318519296221, 8669.87442089114, 9130.315226357918, 9134.543272836465, 9757.12185903272, 9890.406575605486, 9436.575354109218, 9776.706698798764, 9820.050389874099, 9903.900960990586, 9922.950385248028], "value": 6840.433289285512, "unit": "score"}], "name": "dromaeo_css", "value": 7724.458069510641, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 119.0, 108.0, 115.0, 118.0, 117.0, 104.0, 115.0, 115.0, 115.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 129.0, 181.0, 190.0, 144.0, 139.0, 121.0, 152.0, 150.0, 119.0], "value": 147.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 198.0, 198.0, 198.0, 194.0, 195.0, 195.0, 196.0, 191.0, 193.0], "value": 195.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 75.0, 78.0, 143.0, 57.0, 67.0, 95.0, 94.0, 152.0, 59.0], "value": 88.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 124.0, 76.0, 80.0, 83.0, 119.0, 80.0, 81.0, 119.0], "value": 92.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 96.0, 131.0, 92.0, 88.0, 94.0, 94.0, 121.0, 89.0], "value": 98.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 96.0, 97.0, 97.0, 103.0, 152.0, 97.0, 121.0, 103.0, 96.0], "value": 106.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 126.0, 140.0, 109.0, 146.0, 84.0, 85.0, 136.0, 142.0], "value": 115.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 64.0, 63.0, 63.0, 62.0, 69.0, 107.0, 100.0, 91.0], "value": 73.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 68.0, 66.0, 79.0, 69.0, 67.0, 66.0, 66.0, 66.0], "value": 67.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 81.0, 79.0, 82.0, 80.0, 82.0, 79.0, 82.0, 110.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 125.0, 127.0, 125.0, 125.0, 126.0, 125.0, 126.0, 142.0, 138.0], "value": 128.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 169.0, 173.0, 218.0, 211.0, 168.0, 165.0, 165.0, 164.0, 163.0], "value": 176.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 69.0, 66.0, 69.0, 64.0, 64.0, 69.0, 66.0, 67.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1553.7000000000003, "alertThreshold": 2.0}]} 12:41:55 INFO - Return code: 0 12:41:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:41:55 ERROR - # TBPL SUCCESS # 12:41:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:41:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:41:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:41:55 INFO - 2016-07-08 12:41:55.090 screenresolution[1688:10639] starting screenresolution argv=screenresolution get 12:41:55 INFO - 2016-07-08 12:41:55.092 screenresolution[1688:10639] Display 0: 1600x1200x32@60 12:41:55 INFO - 2016-07-08 12:41:55.098 screenresolution[1689:10642] starting screenresolution argv=screenresolution list 12:41:55 INFO - Available Modes on Display 0 12:41:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:41:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:41:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:41:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:41:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:41:55 INFO - Intel Iris: 12:41:55 INFO - Chipset Model: Intel Iris 12:41:55 INFO - Type: GPU 12:41:55 INFO - Bus: Built-In 12:41:55 INFO - VRAM (Dynamic, Max): 1536 MB 12:41:55 INFO - Vendor: Intel (0x8086) 12:41:55 INFO - Device ID: 0x0a2e 12:41:55 INFO - Revision ID: 0x0009 12:41:55 INFO - Displays: 12:41:55 INFO - Display: 12:41:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:41:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:41:55 INFO - Display Serial Number: 1600x1200 60 12:41:55 INFO - Main Display: Yes 12:41:55 INFO - Mirror: Off 12:41:55 INFO - Online: Yes 12:41:55 INFO - Rotation: Supported 12:41:55 INFO - Return code: 0 12:41:55 INFO - Running post-action listener: _resource_record_post_action 12:41:55 INFO - Running post-run listener: _resource_record_post_run 12:41:56 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 31710720; Write bytes: 496254976; Read time: 195; Write time: 1561 12:41:56 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 185577984; Write bytes: 184766464; Read time: 17149; Write time: 385 12:41:56 INFO - run-tests - Wall time: 1171s; CPU: 54.0%; Read bytes: 30789120; Write bytes: 299952640; Read time: 185; Write time: 1134 12:41:56 INFO - Running post-run listener: _upload_blobber_files 12:41:56 INFO - Blob upload gear active. 12:41:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:41:56 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 12:41:56 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:41:56 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:41:56 INFO - (blobuploader) - INFO - Open directory for files ... 12:41:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:41:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:41:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:41:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:41:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:41:57 INFO - (blobuploader) - INFO - Done attempting. 12:41:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:41:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:41:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:41:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:41:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:41:58 INFO - (blobuploader) - INFO - Done attempting. 12:41:58 INFO - (blobuploader) - INFO - Iteration through files over. 12:41:58 INFO - Return code: 0 12:41:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:41:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:41:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/38c741c1f9be6df14d3b4d667808505218ab49c1048d627a5baa376a68898a8dfd5a7d4c1509476a75590e2f4e5cf1bcab5b4a8e1fc0e099166a89752e3a654a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2bf3782fb4d25c8fa4a655851ad460ccef4fe9badfe707d43e696ec4f25f6d7360cbbb9123c06a262d9fbb03835c38407988f4e717db180b391f9379dfbfba4f"} 12:41:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:41:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:41:58 INFO - Contents: 12:41:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/38c741c1f9be6df14d3b4d667808505218ab49c1048d627a5baa376a68898a8dfd5a7d4c1509476a75590e2f4e5cf1bcab5b4a8e1fc0e099166a89752e3a654a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2bf3782fb4d25c8fa4a655851ad460ccef4fe9badfe707d43e696ec4f25f6d7360cbbb9123c06a262d9fbb03835c38407988f4e717db180b391f9379dfbfba4f"} 12:41:58 INFO - Running post-run listener: copy_logs_to_upload_dir 12:41:58 INFO - Copying logs to upload dir... 12:41:58 INFO - mkdir: /builds/slave/test/build/upload/logs 12:41:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.768195 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-08 12:41:58.676989) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 12:41:58.682551) ========= 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.25zIUEcJsC/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.YtyTLGDyri/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/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-Beta/sha512/38c741c1f9be6df14d3b4d667808505218ab49c1048d627a5baa376a68898a8dfd5a7d4c1509476a75590e2f4e5cf1bcab5b4a8e1fc0e099166a89752e3a654a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2bf3782fb4d25c8fa4a655851ad460ccef4fe9badfe707d43e696ec4f25f6d7360cbbb9123c06a262d9fbb03835c38407988f4e717db180b391f9379dfbfba4f"} build_url:https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011049 build_url: 'https://queue.taskcluster.net/v1/task/W7dbl0ePQ26rKawLN_F5rw/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/38c741c1f9be6df14d3b4d667808505218ab49c1048d627a5baa376a68898a8dfd5a7d4c1509476a75590e2f4e5cf1bcab5b4a8e1fc0e099166a89752e3a654a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2bf3782fb4d25c8fa4a655851ad460ccef4fe9badfe707d43e696ec4f25f6d7360cbbb9123c06a262d9fbb03835c38407988f4e717db180b391f9379dfbfba4f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 12:41:58.719708) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 12:41:58.720527) ========= 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.25zIUEcJsC/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.YtyTLGDyri/Listeners TMPDIR=/var/folders/ny/jtz5t9tn5hsgvkr_fh45rj8r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005224 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 12:41:58.769923) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 12:41:58.770281) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 12:41:58.770640) ========= ========= Total master_lag: 0.26 =========