builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0200 starttime: 1461429765.76 results: success (0) buildid: 20160423070820 builduid: 0b589bb5cc7c4e91b12de30e70fdb2dc revision: 81e5bf11a1e69b4748d11854bdbb0f4195edcb7b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-23 09:42:45.764996) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-23 09:42:45.767071) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-23 09:42:45.767772) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-23 09:42:45.876217) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 09:42:45.876495) ========= 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.uFI24kKKnB/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.73h3SlkTNf/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-23 09:42:45-- 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% 42.3M=0s 2016-04-23 09:42:46 (42.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.505754 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 09:42:46.399775) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 09:42:46.400374) ========= 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.uFI24kKKnB/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.73h3SlkTNf/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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.020450 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 09:42:46.453301) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 09:42:46.454085) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 81e5bf11a1e69b4748d11854bdbb0f4195edcb7b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 81e5bf11a1e69b4748d11854bdbb0f4195edcb7b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uFI24kKKnB/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.73h3SlkTNf/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-23 09:42:46,528 truncating revision to first 12 chars 2016-04-23 09:42:46,528 Setting DEBUG logging. 2016-04-23 09:42:46,528 attempt 1/10 2016-04-23 09:42:46,528 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/81e5bf11a1e6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-23 09:42:46,824 unpacking tar archive at: fx-team-81e5bf11a1e6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.537838 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-23 09:42:47.012404) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-23 09:42:47.012724) ========= script_repo_revision: 81e5bf11a1e69b4748d11854bdbb0f4195edcb7b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-23 09:42:47.013177) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-23 09:42:47.013475) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-23 09:42:47.037820) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 50 secs) (at 2016-04-23 09:42:47.038145) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uFI24kKKnB/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.73h3SlkTNf/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:42:47 INFO - MultiFileLogger online at 20160423 09:42:47 in /builds/slave/test 09:42:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:42:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:42:47 INFO - {'append_to_log': False, 09:42:47 INFO - 'base_work_dir': '/builds/slave/test', 09:42:47 INFO - 'blob_upload_branch': 'Fx-Team', 09:42:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:42:47 INFO - 'branch': 'Fx-Team', 09:42:47 INFO - 'buildbot_json_path': 'buildprops.json', 09:42:47 INFO - 'config_files': ('talos/mac_config.py',), 09:42:47 INFO - 'default_actions': ('clobber', 09:42:47 INFO - 'read-buildbot-config', 09:42:47 INFO - 'download-and-extract', 09:42:47 INFO - 'populate-webroot', 09:42:47 INFO - 'create-virtualenv', 09:42:47 INFO - 'install', 09:42:47 INFO - 'run-tests'), 09:42:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:42:47 INFO - 'download_minidump_stackwalk': True, 09:42:47 INFO - 'download_symbols': 'ondemand', 09:42:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:42:47 INFO - 'tooltool.py': '/tools/tooltool.py', 09:42:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:42:47 INFO - '/tools/misc-python/virtualenv.py')}, 09:42:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:42:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:42:47 INFO - 'installer_path': 'installer.exe', 09:42:47 INFO - 'log_level': 'info', 09:42:47 INFO - 'log_name': 'talos', 09:42:47 INFO - 'log_to_console': True, 09:42:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:42:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:42:47 INFO - 'opt_config_files': (), 09:42:47 INFO - 'pip_index': False, 09:42:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:42:47 INFO - 'cmd': ('bash', 09:42:47 INFO - '-c', 09:42:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:42:47 INFO - 'enabled': True, 09:42:47 INFO - 'halt_on_failure': False, 09:42:47 INFO - 'name': 'check_screen_resolution'},), 09:42:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:42:47 INFO - 'cmd': ('bash', 09:42:47 INFO - '-c', 09:42:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:42:47 INFO - 'enabled': True, 09:42:47 INFO - 'halt_on_failure': False, 09:42:47 INFO - 'name': 'check_screen_resolution'},), 09:42:47 INFO - 'run_cmd_checks_enabled': True, 09:42:47 INFO - 'sps_profile': False, 09:42:47 INFO - 'sps_profile_interval': 0, 09:42:47 INFO - 'suite': 'dromaeojs-e10s', 09:42:47 INFO - 'system_bits': '32', 09:42:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:42:47 INFO - 'title': 't-yosemite-r7-0200', 09:42:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:42:47 INFO - 'use_talos_json': True, 09:42:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:42:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:42:47 INFO - 'work_dir': 'build'} 09:42:47 INFO - ##### 09:42:47 INFO - ##### Running clobber step. 09:42:47 INFO - ##### 09:42:47 INFO - Running pre-action listener: _resource_record_pre_action 09:42:47 INFO - Running main action method: clobber 09:42:47 INFO - rmtree: /builds/slave/test/build 09:42:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:42:48 INFO - Running post-action listener: _resource_record_post_action 09:42:48 INFO - ##### 09:42:48 INFO - ##### Running read-buildbot-config step. 09:42:48 INFO - ##### 09:42:48 INFO - Running pre-action listener: _resource_record_pre_action 09:42:48 INFO - Running main action method: read_buildbot_config 09:42:48 INFO - Using buildbot properties: 09:42:48 INFO - { 09:42:48 INFO - "project": "", 09:42:48 INFO - "product": "firefox", 09:42:48 INFO - "script_repo_revision": "production", 09:42:48 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 09:42:48 INFO - "repository": "", 09:42:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 09:42:48 INFO - "buildid": "20160423070820", 09:42:48 INFO - "pgo_build": "False", 09:42:48 INFO - "basedir": "/builds/slave/test", 09:42:48 INFO - "buildnumber": 628, 09:42:48 INFO - "slavename": "t-yosemite-r7-0200", 09:42:48 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:42:48 INFO - "platform": "yosemite_r7", 09:42:48 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 09:42:48 INFO - "branch": "fx-team", 09:42:48 INFO - "repo_path": "integration/fx-team", 09:42:48 INFO - "revision": "81e5bf11a1e69b4748d11854bdbb0f4195edcb7b", 09:42:48 INFO - "stage_platform": "macosx64", 09:42:48 INFO - "builduid": "0b589bb5cc7c4e91b12de30e70fdb2dc", 09:42:48 INFO - "slavebuilddir": "test" 09:42:48 INFO - } 09:42:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 09:42:48 INFO - Running post-action listener: _resource_record_post_action 09:42:48 INFO - ##### 09:42:48 INFO - ##### Running download-and-extract step. 09:42:48 INFO - ##### 09:42:48 INFO - Running pre-action listener: _resource_record_pre_action 09:42:48 INFO - Running main action method: download_and_extract 09:42:48 INFO - mkdir: /builds/slave/test/build/tests 09:42:48 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')]} 09:42:48 INFO - https://queue.taskcluster.net/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:42:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 09:42:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 09:42:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:42:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 09:43:18 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json: timed out 09:43:18 INFO - retry: attempt #1 caught exception: timed out 09:43:18 INFO - retry: Failed, sleeping 30 seconds before retrying 09:43:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #2 09:43:57 INFO - Downloaded 1199 bytes. 09:43:57 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 09:43:57 INFO - Using the following test package requirements: 09:43:57 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:43:57 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:43:57 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 09:43:57 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:43:57 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 09:43:57 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:43:57 INFO - u'jsshell-mac.zip'], 09:43:57 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:43:57 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 09:43:57 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 09:43:57 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:43:57 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 09:43:57 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:43:57 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 09:43:57 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:43:57 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 09:43:57 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 09:43:57 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 09:43:57 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:43:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:43:57 INFO - https://queue.taskcluster.net/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:43:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:43:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:43:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:43:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:44:02 INFO - Downloaded 18109585 bytes. 09:44:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:44:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:44:05 INFO - Return code: 0 09:44:05 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:44:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:05 INFO - https://queue.taskcluster.net/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:44:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:44:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:44:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 09:44:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:44:06 INFO - Downloaded 18109585 bytes. 09:44:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:44:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:44:10 INFO - Return code: 0 09:44: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')]} 09:44:10 INFO - https://queue.taskcluster.net/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:44:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 09:44:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 09:44:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 09:44:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:44:11 INFO - Downloaded 10840145 bytes. 09:44:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:44:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:44:11 INFO - Return code: 0 09:44: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')]} 09:44:11 INFO - https://queue.taskcluster.net/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:44:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:44:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:44:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 09:44:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 09:44:15 INFO - Downloaded 110124604 bytes. 09:44:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:44:15 INFO - mkdir: /builds/slave/test/properties 09:44:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:44:15 INFO - Writing to file /builds/slave/test/properties/build_url 09:44:15 INFO - Contents: 09:44:15 INFO - build_url:https://queue.taskcluster.net/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 09:44:16 INFO - Running post-action listener: _resource_record_post_action 09:44:16 INFO - Running post-action listener: set_extra_try_arguments 09:44:16 INFO - ##### 09:44:16 INFO - ##### Running populate-webroot step. 09:44:16 INFO - ##### 09:44:16 INFO - Running pre-action listener: _resource_record_pre_action 09:44:16 INFO - Running main action method: populate_webroot 09:44:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:44:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:44:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:44:16 INFO - '--tppagecycles', 09:44:16 INFO - '7'], 09:44:16 INFO - 'tests': ['tsvgm']}}, 09:44:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:44:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:44:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:44:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:44:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:44:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:44:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:16 INFO - 'talos_options': ['--disable-e10s'], 09:44:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:44:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:44:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:16 INFO - 'talos_options': ['--disable-e10s'], 09:44:16 INFO - 'tests': ['damp', 'tps']}, 09:44:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:16 INFO - 'tests': ['damp', 'tps']}, 09:44:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:44:16 INFO - 'tests': ['dromaeo_dom']}, 09:44:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:44:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:44:16 INFO - 'tests': ['a11yr', 09:44:16 INFO - 'ts_paint', 09:44:16 INFO - 'tpaint', 09:44:16 INFO - 'sessionrestore', 09:44:16 INFO - 'sessionrestore_no_auto_restore', 09:44:16 INFO - 'tabpaint']}, 09:44:16 INFO - 'other-e10s': {'tests': ['a11yr', 09:44:16 INFO - 'ts_paint', 09:44:16 INFO - 'tpaint', 09:44:16 INFO - 'sessionrestore', 09:44:16 INFO - 'sessionrestore_no_auto_restore', 09:44:16 INFO - 'tabpaint']}, 09:44:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:44:16 INFO - 'ts_paint', 09:44:16 INFO - 'tpaint', 09:44:16 INFO - 'sessionrestore', 09:44:16 INFO - 'sessionrestore_no_auto_restore', 09:44:16 INFO - 'tabpaint']}, 09:44:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:44:16 INFO - 'ts_paint', 09:44:16 INFO - 'tpaint', 09:44:16 INFO - 'sessionrestore', 09:44:16 INFO - 'sessionrestore_no_auto_restore', 09:44:16 INFO - 'tabpaint']}, 09:44:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:44:16 INFO - 'tests': ['a11yr', 09:44:16 INFO - 'ts_paint', 09:44:16 INFO - 'tpaint', 09:44:16 INFO - 'sessionrestore', 09:44:16 INFO - 'sessionrestore_no_auto_restore', 09:44:16 INFO - 'tabpaint']}, 09:44:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:44:16 INFO - 'tests': ['a11yr', 09:44:16 INFO - 'ts_paint', 09:44:16 INFO - 'tpaint', 09:44:16 INFO - 'sessionrestore', 09:44:16 INFO - 'sessionrestore_no_auto_restore', 09:44:16 INFO - 'tabpaint']}, 09:44:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:44:16 INFO - 'tests': ['tsvgx', 09:44:16 INFO - 'tsvgr_opacity', 09:44:16 INFO - 'tart', 09:44:16 INFO - 'tscrollx', 09:44:16 INFO - 'cart']}, 09:44:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:44:16 INFO - 'tsvgr_opacity', 09:44:16 INFO - 'tart', 09:44:16 INFO - 'tscrollx', 09:44:16 INFO - 'cart']}, 09:44:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:16 INFO - 'talos_options': ['--disable-e10s'], 09:44:16 INFO - 'tests': ['tp5o']}, 09:44:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:16 INFO - 'tests': ['tp5o']}, 09:44:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:16 INFO - 'talos_options': ['--disable-e10s', 09:44:16 INFO - '--xperf_path', 09:44:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:44:16 INFO - 'tests': ['tp5n']}, 09:44:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:44:16 INFO - 'talos_options': ['--xperf_path', 09:44:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:44:16 INFO - 'tests': ['tp5n']}}, 09:44:16 INFO - 'talos.zip': {'path': '', 09:44:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:44:16 INFO - Running post-action listener: _resource_record_post_action 09:44:16 INFO - ##### 09:44:16 INFO - ##### Running create-virtualenv step. 09:44:16 INFO - ##### 09:44:16 INFO - Running pre-action listener: _resource_record_pre_action 09:44:16 INFO - Running main action method: create_virtualenv 09:44:16 INFO - Creating virtualenv /builds/slave/test/build/venv 09:44:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:44:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:44:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:44:16 INFO - Using real prefix '/tools/python27' 09:44:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:44:17 INFO - Installing distribute.............................................................................................................................................................................................done. 09:44:20 INFO - Installing pip.................done. 09:44:20 INFO - Return code: 0 09:44:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:44: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')]} 09:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44: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')]} 09:44:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:20 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 0x10ac75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac85420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda10d70cd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab14e00>, '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 0x10a97ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0fb20>, '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.uFI24kKKnB/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.73h3SlkTNf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:44:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:44:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:44:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uFI24kKKnB/Render', 09:44:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:20 INFO - 'HOME': '/Users/cltbld', 09:44:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:20 INFO - 'LOGNAME': 'cltbld', 09:44:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:20 INFO - 'MOZ_NO_REMOTE': '1', 09:44:20 INFO - 'NO_EM_RESTART': '1', 09:44:20 INFO - 'PAGER': '/bin/cat', 09:44:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:20 INFO - 'PWD': '/builds/slave/test', 09:44:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:20 INFO - 'SHELL': '/bin/bash', 09:44:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.73h3SlkTNf/Listeners', 09:44:20 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 09:44:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:20 INFO - 'USER': 'cltbld', 09:44:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:20 INFO - 'XPC_FLAGS': '0x0', 09:44:20 INFO - 'XPC_SERVICE_NAME': '0', 09:44:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:20 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:44:20 INFO - Cleaning up... 09:44:20 INFO - Return code: 0 09:44:20 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:44: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')]} 09:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44: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')]} 09:44:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:20 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 0x10ac75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac85420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda10d70cd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab14e00>, '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 0x10a97ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0fb20>, '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.uFI24kKKnB/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.73h3SlkTNf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:44:20 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 09:44:20 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 09:44:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uFI24kKKnB/Render', 09:44:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:20 INFO - 'HOME': '/Users/cltbld', 09:44:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:20 INFO - 'LOGNAME': 'cltbld', 09:44:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:20 INFO - 'MOZ_NO_REMOTE': '1', 09:44:20 INFO - 'NO_EM_RESTART': '1', 09:44:20 INFO - 'PAGER': '/bin/cat', 09:44:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:20 INFO - 'PWD': '/builds/slave/test', 09:44:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:20 INFO - 'SHELL': '/bin/bash', 09:44:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.73h3SlkTNf/Listeners', 09:44:20 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 09:44:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:20 INFO - 'USER': 'cltbld', 09:44:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:20 INFO - 'XPC_FLAGS': '0x0', 09:44:20 INFO - 'XPC_SERVICE_NAME': '0', 09:44:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:20 INFO - Downloading/unpacking psutil>=0.7.1 09:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:44:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:44:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:44:23 INFO - Installing collected packages: psutil 09:44:23 INFO - Running setup.py install for psutil 09:44:23 INFO - building 'psutil._psutil_osx' extension 09:44:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:44:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:44:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:44:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:44:24 INFO - building 'psutil._psutil_posix' extension 09:44:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:44:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:44:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:44:24 INFO - ^ 09:44:24 INFO - 1 warning generated. 09:44:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:44:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:44:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:44:24 INFO - Successfully installed psutil 09:44:24 INFO - Cleaning up... 09:44:24 INFO - Return code: 0 09:44:24 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:44: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')]} 09:44:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44: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')]} 09:44:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:24 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 0x10ac75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac85420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda10d70cd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab14e00>, '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 0x10a97ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0fb20>, '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.uFI24kKKnB/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.73h3SlkTNf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:44:24 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 09:44:24 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 09:44:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uFI24kKKnB/Render', 09:44:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:24 INFO - 'HOME': '/Users/cltbld', 09:44:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:24 INFO - 'LOGNAME': 'cltbld', 09:44:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:24 INFO - 'MOZ_NO_REMOTE': '1', 09:44:24 INFO - 'NO_EM_RESTART': '1', 09:44:24 INFO - 'PAGER': '/bin/cat', 09:44:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:24 INFO - 'PWD': '/builds/slave/test', 09:44:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:24 INFO - 'SHELL': '/bin/bash', 09:44:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.73h3SlkTNf/Listeners', 09:44:24 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 09:44:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:24 INFO - 'USER': 'cltbld', 09:44:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:24 INFO - 'XPC_FLAGS': '0x0', 09:44:24 INFO - 'XPC_SERVICE_NAME': '0', 09:44:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:24 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:44:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:27 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:44:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:44:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:44:27 INFO - Installing collected packages: mozsystemmonitor 09:44:27 INFO - Running setup.py install for mozsystemmonitor 09:44:27 INFO - Successfully installed mozsystemmonitor 09:44:27 INFO - Cleaning up... 09:44:27 INFO - Return code: 0 09:44:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:44:27 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')]} 09:44:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:27 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')]} 09:44:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:27 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 0x10ac75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac85420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda10d70cd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab14e00>, '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 0x10a97ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0fb20>, '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.uFI24kKKnB/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.73h3SlkTNf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:44:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:44:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:44:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uFI24kKKnB/Render', 09:44:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:27 INFO - 'HOME': '/Users/cltbld', 09:44:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:27 INFO - 'LOGNAME': 'cltbld', 09:44:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:27 INFO - 'MOZ_NO_REMOTE': '1', 09:44:27 INFO - 'NO_EM_RESTART': '1', 09:44:27 INFO - 'PAGER': '/bin/cat', 09:44:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:27 INFO - 'PWD': '/builds/slave/test', 09:44:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:27 INFO - 'SHELL': '/bin/bash', 09:44:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.73h3SlkTNf/Listeners', 09:44:27 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 09:44:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:27 INFO - 'USER': 'cltbld', 09:44:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:27 INFO - 'XPC_FLAGS': '0x0', 09:44:27 INFO - 'XPC_SERVICE_NAME': '0', 09:44:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:27 INFO - Downloading/unpacking blobuploader==1.2.4 09:44:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:30 INFO - Downloading blobuploader-1.2.4.tar.gz 09:44:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:44:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:44:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:44: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 09:44: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 09:44: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 09:44: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 09:44: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 09:44: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 09:44:31 INFO - Downloading docopt-0.6.1.tar.gz 09:44:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:44:31 INFO - Installing collected packages: blobuploader, requests, docopt 09:44:31 INFO - Running setup.py install for blobuploader 09:44:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:44:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:44:31 INFO - Running setup.py install for requests 09:44:32 INFO - Running setup.py install for docopt 09:44:32 INFO - Successfully installed blobuploader requests docopt 09:44:32 INFO - Cleaning up... 09:44:32 INFO - Return code: 0 09:44:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:44:32 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')]} 09:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:32 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')]} 09:44:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:32 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 0x10ac75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac85420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda10d70cd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab14e00>, '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 0x10a97ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0fb20>, '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.uFI24kKKnB/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.73h3SlkTNf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:44:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:44:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:44:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uFI24kKKnB/Render', 09:44:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:32 INFO - 'HOME': '/Users/cltbld', 09:44:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:32 INFO - 'LOGNAME': 'cltbld', 09:44:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:32 INFO - 'MOZ_NO_REMOTE': '1', 09:44:32 INFO - 'NO_EM_RESTART': '1', 09:44:32 INFO - 'PAGER': '/bin/cat', 09:44:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:32 INFO - 'PWD': '/builds/slave/test', 09:44:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:32 INFO - 'SHELL': '/bin/bash', 09:44:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.73h3SlkTNf/Listeners', 09:44:32 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 09:44:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:32 INFO - 'USER': 'cltbld', 09:44:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:32 INFO - 'XPC_FLAGS': '0x0', 09:44:32 INFO - 'XPC_SERVICE_NAME': '0', 09:44:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:44:32 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-NUj2wV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:44:32 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-eK8oCF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:44:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:44:32 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-Rs60bH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:44:33 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-yFmqlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:44:33 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-ASeSg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:44:33 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-BShEq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:44:33 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-aNyPY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:44:33 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-vr6t43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:44:33 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-ULrd32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:44:33 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-qA9nds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:44:33 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-0PqMUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:44:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:44:33 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-Jv8qNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:44:34 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-irXUnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:44:34 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-7df5zd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:34 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-Bfcdi7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:44:34 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-ws7wmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:44:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:44:34 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-Yis0mb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:44:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:44:34 INFO - Running setup.py install for manifestparser 09:44:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:44:34 INFO - Running setup.py install for mozcrash 09:44:34 INFO - Running setup.py install for mozdebug 09:44:34 INFO - Running setup.py install for mozdevice 09:44:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:44:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:44:35 INFO - Running setup.py install for mozfile 09:44:35 INFO - Running setup.py install for mozhttpd 09:44:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:44:35 INFO - Running setup.py install for mozinfo 09:44:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:44:35 INFO - Running setup.py install for mozInstall 09:44:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:44:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:44:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:44:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:44:35 INFO - Running setup.py install for mozleak 09:44:35 INFO - Running setup.py install for mozlog 09:44:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:44:36 INFO - Running setup.py install for moznetwork 09:44:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:44:36 INFO - Running setup.py install for mozprocess 09:44:36 INFO - Running setup.py install for mozprofile 09:44:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:44:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:44:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:44:36 INFO - Running setup.py install for mozrunner 09:44:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:44:36 INFO - Running setup.py install for mozscreenshot 09:44:36 INFO - Running setup.py install for moztest 09:44:36 INFO - Running setup.py install for mozversion 09:44:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:44:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:44:37 INFO - Cleaning up... 09:44:37 INFO - Return code: 0 09:44:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:44:37 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')]} 09:44:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:37 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')]} 09:44:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:37 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 0x10ac75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac85420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda10d70cd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab14e00>, '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 0x10a97ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0fb20>, '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.uFI24kKKnB/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.73h3SlkTNf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:44:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:44:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:44:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uFI24kKKnB/Render', 09:44:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:37 INFO - 'HOME': '/Users/cltbld', 09:44:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:37 INFO - 'LOGNAME': 'cltbld', 09:44:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:37 INFO - 'MOZ_NO_REMOTE': '1', 09:44:37 INFO - 'NO_EM_RESTART': '1', 09:44:37 INFO - 'PAGER': '/bin/cat', 09:44:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:37 INFO - 'PWD': '/builds/slave/test', 09:44:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:37 INFO - 'SHELL': '/bin/bash', 09:44:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.73h3SlkTNf/Listeners', 09:44:37 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 09:44:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:37 INFO - 'USER': 'cltbld', 09:44:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:37 INFO - 'XPC_FLAGS': '0x0', 09:44:37 INFO - 'XPC_SERVICE_NAME': '0', 09:44:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:44:37 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-TDOp1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:44:37 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:44:37 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-APGSZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:44:37 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)) 09:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:44:37 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-_KcGoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:44:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:44:37 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-KWgfBd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:44:37 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)) 09:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:44:37 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-Prp61t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:44:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:44:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:44:37 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-1zAbHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:44:38 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-TQ684Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:44:38 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-pa_e9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:44:38 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-Gw8kJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:44:38 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-wNA6Bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:44:38 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)) 09:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:44:38 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-987XkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:44:38 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-N9Y94P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:44:38 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)) 09:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:44:38 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-Za9n0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:44:38 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-6QVUqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:44:38 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)) 09:44:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:38 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-jkZohp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:44:39 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-jp_M_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:44:39 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)) 09:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:44:39 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-eBUqJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:44:39 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)) 09:44:39 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)) 09:44:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:44:39 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)) 09:44:39 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)) 09:44:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:41 INFO - Downloading blessings-1.6.tar.gz 09:44:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:44:41 INFO - Installing collected packages: blessings 09:44:41 INFO - Running setup.py install for blessings 09:44:41 INFO - Successfully installed blessings 09:44:41 INFO - Cleaning up... 09:44:42 INFO - Return code: 0 09:44:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:44:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:44:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:44:42 INFO - Reading from file tmpfile_stdout 09:44:42 INFO - Current package versions: 09:44:42 INFO - blessings == 1.6 09:44:42 INFO - blobuploader == 1.2.4 09:44:42 INFO - docopt == 0.6.1 09:44:42 INFO - manifestparser == 1.1 09:44:42 INFO - mozInstall == 1.12 09:44:42 INFO - mozcrash == 0.17 09:44:42 INFO - mozdebug == 0.1 09:44:42 INFO - mozdevice == 0.48 09:44:42 INFO - mozfile == 1.2 09:44:42 INFO - mozhttpd == 0.7 09:44:42 INFO - mozinfo == 0.9 09:44:42 INFO - mozleak == 0.1 09:44:42 INFO - mozlog == 3.1 09:44:42 INFO - moznetwork == 0.27 09:44:42 INFO - mozprocess == 0.22 09:44:42 INFO - mozprofile == 0.28 09:44:42 INFO - mozrunner == 6.11 09:44:42 INFO - mozscreenshot == 0.1 09:44:42 INFO - mozsystemmonitor == 0.0 09:44:42 INFO - moztest == 0.7 09:44:42 INFO - mozversion == 1.4 09:44:42 INFO - psutil == 3.1.1 09:44:42 INFO - requests == 1.2.3 09:44:42 INFO - wsgiref == 0.1.2 09:44:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:42 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 0x10ac75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac85420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda10d70cd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab14e00>, '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 0x10a97ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0fb20>, '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.uFI24kKKnB/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.73h3SlkTNf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:44:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:44:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:44:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uFI24kKKnB/Render', 09:44:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:42 INFO - 'HOME': '/Users/cltbld', 09:44:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:42 INFO - 'LOGNAME': 'cltbld', 09:44:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:42 INFO - 'MOZ_NO_REMOTE': '1', 09:44:42 INFO - 'NO_EM_RESTART': '1', 09:44:42 INFO - 'PAGER': '/bin/cat', 09:44:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:42 INFO - 'PWD': '/builds/slave/test', 09:44:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:42 INFO - 'SHELL': '/bin/bash', 09:44:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.73h3SlkTNf/Listeners', 09:44:42 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 09:44:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:42 INFO - 'USER': 'cltbld', 09:44:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:42 INFO - 'XPC_FLAGS': '0x0', 09:44:42 INFO - 'XPC_SERVICE_NAME': '0', 09:44:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:44:42 INFO - Cleaning up... 09:44:42 INFO - Return code: 0 09:44:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ac75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac85420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda10d70cd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab14e00>, '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 0x10a97ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0fb20>, '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.uFI24kKKnB/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.73h3SlkTNf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:44:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:44:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:44:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uFI24kKKnB/Render', 09:44:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:42 INFO - 'HOME': '/Users/cltbld', 09:44:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:42 INFO - 'LOGNAME': 'cltbld', 09:44:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:42 INFO - 'MOZ_NO_REMOTE': '1', 09:44:42 INFO - 'NO_EM_RESTART': '1', 09:44:42 INFO - 'PAGER': '/bin/cat', 09:44:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:42 INFO - 'PWD': '/builds/slave/test', 09:44:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:42 INFO - 'SHELL': '/bin/bash', 09:44:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.73h3SlkTNf/Listeners', 09:44:42 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 09:44:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:42 INFO - 'USER': 'cltbld', 09:44:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:42 INFO - 'XPC_FLAGS': '0x0', 09:44:42 INFO - 'XPC_SERVICE_NAME': '0', 09:44:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:42 INFO - Downloading/unpacking jsonschema 09:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:44:45 INFO - Downloading/unpacking functools32 (from jsonschema) 09:44:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:45 INFO - Downloading functools32-3.2.3-2.tar.gz 09:44:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:44:45 INFO - warning: no files found matching '*.txt' 09:44:45 INFO - no previously-included directories found matching 'build' 09:44:45 INFO - no previously-included directories found matching 'dist' 09:44:45 INFO - no previously-included directories found matching '.git*' 09:44:45 INFO - Installing collected packages: jsonschema, functools32 09:44:45 INFO - Running setup.py install for functools32 09:44:45 INFO - warning: no files found matching '*.txt' 09:44:45 INFO - no previously-included directories found matching 'build' 09:44:45 INFO - no previously-included directories found matching 'dist' 09:44:45 INFO - no previously-included directories found matching '.git*' 09:44:45 INFO - Successfully installed jsonschema functools32 09:44:45 INFO - Cleaning up... 09:44:46 INFO - Return code: 0 09:44:46 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:44:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ac75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac85420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda10d70cd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab14e00>, '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 0x10a97ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0fb20>, '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.uFI24kKKnB/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.73h3SlkTNf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:44:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:44:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:44:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uFI24kKKnB/Render', 09:44:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:46 INFO - 'HOME': '/Users/cltbld', 09:44:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:46 INFO - 'LOGNAME': 'cltbld', 09:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:46 INFO - 'MOZ_NO_REMOTE': '1', 09:44:46 INFO - 'NO_EM_RESTART': '1', 09:44:46 INFO - 'PAGER': '/bin/cat', 09:44:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:46 INFO - 'PWD': '/builds/slave/test', 09:44:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:46 INFO - 'SHELL': '/bin/bash', 09:44:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.73h3SlkTNf/Listeners', 09:44:46 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 09:44:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:46 INFO - 'USER': 'cltbld', 09:44:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:46 INFO - 'XPC_FLAGS': '0x0', 09:44:46 INFO - 'XPC_SERVICE_NAME': '0', 09:44:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:46 INFO - Downloading/unpacking flake8 09:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:48 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:44:48 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:49 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:44:49 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:44: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 09:44: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 09:44: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 09:44: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 09:44: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 09:44: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 09:44:49 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:44: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 09:44: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 09:44: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 09:44: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 09:44: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 09:44: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 09:44:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:44:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:44:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:44:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:44:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:44:49 INFO - no previously-included directories found matching 'docs/_build' 09:44:49 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:44:50 INFO - Running setup.py install for pep8 09:44:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:44:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:44:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:44:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:44:50 INFO - no previously-included directories found matching 'docs/_build' 09:44:50 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:44:50 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:44:50 INFO - Cleaning up... 09:44:50 INFO - Return code: 0 09:44:50 INFO - Running post-action listener: _resource_record_post_action 09:44:50 INFO - Running post-action listener: _start_resource_monitoring 09:44:50 INFO - Starting resource monitoring. 09:44:50 INFO - ##### 09:44:50 INFO - ##### Running install step. 09:44:50 INFO - ##### 09:44:50 INFO - Running pre-action listener: _resource_record_pre_action 09:44:50 INFO - Running main action method: install 09:44:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:44:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:44:50 INFO - Reading from file tmpfile_stdout 09:44:50 INFO - Detecting whether we're running mozinstall >=1.0... 09:44:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:44:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:44:50 INFO - Reading from file tmpfile_stdout 09:44:50 INFO - Output received: 09:44:50 INFO - Usage: mozinstall [options] installer 09:44:50 INFO - Options: 09:44:50 INFO - -h, --help show this help message and exit 09:44:50 INFO - -d DEST, --destination=DEST 09:44:50 INFO - Directory to install application into. [default: 09:44:50 INFO - "/builds/slave/test"] 09:44:50 INFO - --app=APP Application being installed. [default: firefox] 09:44:50 INFO - mkdir: /builds/slave/test/build/application 09:44:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:44:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:45:24 INFO - Reading from file tmpfile_stdout 09:45:24 INFO - Output received: 09:45:24 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:45:24 INFO - Running post-action listener: _resource_record_post_action 09:45:24 INFO - ##### 09:45:24 INFO - ##### Running run-tests step. 09:45:24 INFO - ##### 09:45:24 INFO - Running pre-action listener: _resource_record_pre_action 09:45:24 INFO - Running main action method: run_tests 09:45:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:45:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:45:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:45:24 INFO - 2016-04-23 09:45:24.200 screenresolution[1920:14287] starting screenresolution argv=screenresolution get 09:45:24 INFO - 2016-04-23 09:45:24.203 screenresolution[1920:14287] Display 0: 1600x1200x32@60 09:45:24 INFO - 2016-04-23 09:45:24.209 screenresolution[1921:14289] starting screenresolution argv=screenresolution list 09:45:24 INFO - Available Modes on Display 0 09:45:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:45:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:45:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:45:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:45:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:45:24 INFO - Intel Iris: 09:45:24 INFO - Chipset Model: Intel Iris 09:45:24 INFO - Type: GPU 09:45:24 INFO - Bus: Built-In 09:45:24 INFO - VRAM (Dynamic, Max): 1536 MB 09:45:24 INFO - Vendor: Intel (0x8086) 09:45:24 INFO - Device ID: 0x0a2e 09:45:24 INFO - Revision ID: 0x0009 09:45:24 INFO - Displays: 09:45:24 INFO - Display: 09:45:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:45:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:45:24 INFO - Display Serial Number: 1600x1200 60 09:45:24 INFO - Main Display: Yes 09:45:24 INFO - Mirror: Off 09:45:24 INFO - Online: Yes 09:45:24 INFO - Rotation: Supported 09:45:24 INFO - Return code: 0 09:45:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:45:24 INFO - Python 2.7.3 09:45:24 INFO - Return code: 0 09:45:24 INFO - grabbing minidump binary from tooltool 09:45: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')]} 09:45: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 0x10ab14e00>, '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 0x10a97ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ab0fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:45: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 09:45: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 09:45:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:45:24 INFO - Return code: 0 09:45:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:45:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:45:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:45:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:45:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:45:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:45:25 INFO - Return code: 0 09:45:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--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 09:45:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0200 --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 09:45:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uFI24kKKnB/Render', 09:45:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:25 INFO - 'HOME': '/Users/cltbld', 09:45:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:25 INFO - 'LOGNAME': 'cltbld', 09:45:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:45:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:45:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:25 INFO - 'MOZ_NO_REMOTE': '1', 09:45:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:45:25 INFO - 'NO_EM_RESTART': '1', 09:45:25 INFO - 'PAGER': '/bin/cat', 09:45:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:25 INFO - 'PWD': '/builds/slave/test', 09:45:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:45:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:25 INFO - 'SHELL': '/bin/bash', 09:45:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.73h3SlkTNf/Listeners', 09:45:25 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 09:45:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:25 INFO - 'USER': 'cltbld', 09:45:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:25 INFO - 'XPC_FLAGS': '0x0', 09:45:25 INFO - 'XPC_SERVICE_NAME': '0', 09:45:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--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 09:45:25 INFO - mozversion application_buildid: 20160423070820 09:45:25 INFO - mozversion application_changeset: 81e5bf11a1e69b4748d11854bdbb0f4195edcb7b 09:45:25 INFO - mozversion application_display_name: Nightly 09:45:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:45:25 INFO - mozversion application_name: Firefox 09:45:25 INFO - mozversion application_remotingname: firefox 09:45:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:45:25 INFO - mozversion application_vendor: Mozilla 09:45:25 INFO - mozversion application_version: 48.0a1 09:45:25 INFO - mozversion platform_buildid: 20160423070820 09:45:25 INFO - mozversion platform_changeset: 81e5bf11a1e69b4748d11854bdbb0f4195edcb7b 09:45:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:45:25 INFO - mozversion platform_version: 48.0a1 09:45:25 INFO - using testdate: 1461429925 09:45:25 INFO - actual date: 1461429925 09:45:25 INFO - starting webserver on 'localhost:51167' 09:45:25 INFO - SUITE-START | Running 2 tests 09:45:25 INFO - TEST-START | dromaeo_css 09:45:25 INFO - operating with platform_type : mac_ 09:45:25 INFO - Initialising browser for dromaeo_css test... 09:45:25 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/tmpnXYzf3/profile http://localhost:51167/getInfo.html) 09:45:28 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 09:45:28 INFO - PROCESS | 1939 | colorDepth:24 09:45:28 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 09:45:28 INFO - PROCESS | 1939 | __metrics 09:45:28 ERROR - PROCESS | 1939 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 09:45:29 INFO - TEST-INFO | 1939: exit 0 09:45:29 INFO - Browser initialized. 09:45:29 INFO - Running cycle 1/1 for dromaeo_css test... 09:45:29 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/tmpnXYzf3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:45:41 INFO - PROCESS | 1942 | RSS: Main: 249344000 09:45:41 INFO - PROCESS | 1942 | 09:46:31 INFO - PROCESS | 1942 | 1461429991040 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] 09:48:42 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:51167/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51167/tests/dromaeo/cssquery-ext.html) 09:48:43 INFO - PROCESS | 1942 | RSS: Main: 261496832 09:48:43 INFO - PROCESS | 1942 | 09:51:45 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:51167/tests/dromaeo/cssquery-ext.html (next: http://localhost:51167/tests/dromaeo/cssquery-jquery.html) 09:51:46 INFO - PROCESS | 1942 | RSS: Main: 273358848 09:51:46 INFO - PROCESS | 1942 | 09:54:48 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:51167/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51167/tests/dromaeo/cssquery-mootools.html) 09:54:48 INFO - PROCESS | 1942 | RSS: Main: 275226624 09:54:48 INFO - PROCESS | 1942 | 09:57:50 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:51167/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51167/tests/dromaeo/cssquery-prototype.html) 09:57:51 INFO - PROCESS | 1942 | RSS: Main: 276959232 09:57:51 INFO - PROCESS | 1942 | 10:00:53 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:51167/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51167/tests/dromaeo/cssquery-yui.html) 10:00:54 INFO - PROCESS | 1942 | RSS: Main: 279822336 10:00:54 INFO - PROCESS | 1942 | 10:03:56 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:51167/tests/dromaeo/cssquery-yui.html (next: http://localhost:51167/tests/dromaeo/cssquery-dojo.html) 10:03:57 INFO - PROCESS | 1942 | RSS: Main: 280862720 10:03:57 INFO - PROCESS | 1942 | 10:03:57 INFO - PROCESS | 1942 | __start_tp_report 10:03:57 INFO - PROCESS | 1942 | _x_x_mozilla_page_load 10:03:57 INFO - PROCESS | 1942 | _x_x_mozilla_page_load_details 10:03:57 INFO - PROCESS | 1942 | |i|pagename|runs| 10:03:57 INFO - PROCESS | 1942 | |0;dojo.html;3462.2902305027465;3468.5317482139903;3472.3228970350774;3493.6855682988526;3493.947590786137;4694.788734506947;5719.742611582473;5775.884482310351;5778.739956701948;5819.767209311628;5288.682679039261;5577.3028371453565;5715.399883012286;5817.534597232222;5849.473547380735;4010.6189911958463;4734.697958061526;5215.530602245806;5265.578753699695;5439.238506609058;5222.92165617516;5381.461853814607;5399.568034557226;5401.378841433247;5429.592780541456;5199.220116982445;5225.869353266161;5255.474452554752;5257.23770053342;5326.280952071479;5288.206768984644;5320.281761962163;5321.36143662763;5322.121849894763;5354.705491197982;4308.849190278325;5262.789488420497;5263.394709608406;5269.7365131743645;5357.357117145929;4894.339264199323;5648.63283886546;5658.122990936411;5727.2268243787385;5761.971190144022;6141.232345956755;6237.812865614039;6254.155688981975;6284.465820405271;6295.401936815995;6191.845203869894;6255.749770009194;6285.151504546873;6303.5272354573135;6306.527010474187;5874.970625146847;5917.644941303535;5919.763209471573;5943.643381397087;6017.819465416044;6396.02731222843;6427.903581446282;6434.8391290218115;6455.7417703292285;6466.6766661666725;6125.9693701531205;6130.673316708229;6237.407446292686;6242.625442473466;6253.093301471352;6234.220722409699;6292.622442653455;6292.716827742763;6300.369963003663;6303.338149494329;6116.969415152985;6214.844628884223;6229.3770622937345;6272.498200143978;6294.905576416358;4682.274247491644;5589.35722391922;6050.727717252734;6159.630422174594;6180.845478862974;4942.901141977212;4955.900881982412;4960.404751429852;4968.527989841032;5010.473900240495;4035.112275299488;4212.368144778307;4406.184855801687;4457.487388950248;4475.686701930898;5766.394528574523;5924.763009479572;5930.644161350247;5934.643921364732;5947.167396564484;6250.499960003189;6268.592541484789;6308.8107356778455;6326.68366581669;6342.904856427157;5967.9701601491715;5979.229520765428;5986.161937328778;5987.970060149672;5994.1608174855555;5407.053765591006;5468.808591699271;5497.038018346853;5500.42245564218;5507.807226747044;6472.352764723584;6509.479241660656;6534.640594767143;6545.803625891231;6579.835504112435;6275.278342990615;6296;6304.243490781136;6314.305426403;6325.177726895474;6376.649284289409;6379.7129129190225;6461.353864613689;6494.0258961156205;6504.959206526933;5084.51697088776;6132.8773424532155;6151.292601350723;6208.534359923115;6210.875782484415;5181.585473162063;5403.757135858696;5515.4760297771645;6167.599106058092;6307.3377295383325;6109.75560977556;6119.015143218386;6124.2588809726185;6180.103884936749;6208.1308616792785;5096.388433388017;6180.412860778219;6192.938070619236;6213.720382582705;6214.471769899428;6007.219061521974;6033.42682445167;6042.274927008787;6117.602355846856;6118.357572455006;6112.388761123852;6143.477804386677;6187.969060154671;6202.255729312511;6209.844753881189;5013.598912086952;6099.237595300588;6118.235220597426;6138.508919286357;6144.907826382697 10:03:57 INFO - PROCESS | 1942 | |1;ext.html;507.3277906773526;511.40166005773244;522.417504482502;592.034982977746;622.2315440431067;38375.616243837554;39240.018999525026;39355.80322098389;39372.40941385878;39467.21065578687;42509.14981700364;42970.355444668276;42983.78508107455;43003.92490187747;43163.78418107905;9511.524423778801;9703.369280996749;9726.367786093917;9754.219662426989;9818.312718109735;8142.674293028272;9571.473568953705;9655.951720241388;9661.661841835537;9696.612135514571;9562.330636855422;9648.276379271547;9656.082672146134;9718.56266468007;9754.707358779213;9585.712428627152;9628.3260171788;9659.951700241489;9661.806763864683;9690.612375504972;9531.475768832712;9574.808503829885;9635.518224088837;9639.518024098767;9644.662436814679;39490.60509394927;39636.80181599102;39844;40313;40440.595594043974;2807.7239683027674;2945.892083851536;3147.4806656901815;3177.8887738929025;3185.6336521300113;26498.470030599474;26766.866165669242;27752.861235693694;28955.56566651502;29617;2684.7717943974785;2729.140320798962;2735.3435175557725;2757.8758955847034;2837.645294338208;450152;450435;452922;455907;456194;52211.73894130505;62900;63788.36211637917;63868.68065659642;63934.36065639377;72898.63550682213;74474;75077.24922750703;75168.62415687887;75236.24763752402;17063.91468042652;17066.31734730597;17488.562785930455;17566.824331756776;17608.031558264418;13122.60632181036;13300.933495332461;13407.195568265935;13429.39567719455;13439.462421503225;19814.20743170257;19955.401337959884;20136.39590812278;20251;20379.4905127373;6707.49693772969;6858.862822743517;8075.757727268191;8114.43198976066;8198.590070496452;820.7948012996751;962.9984815791498;1047.1413440978477;1047.3872784421199;1170.3329102411544;23527.882360588086;29069.85465072661;29109.126726197825;29719.851400742857;30370.696293036788;1026.0867827633413;1131.7510147767478;1135.256407053365;1147.300146910384;1266.0884163402336;15471.96380687013;15546.378144874305;16094.75857862133;16204.270807813677;16482.587935301475;621998;668323;671392;671987;672624;58680.41319586749;60430.697846511364;61072.25174867141;61079.69460152671;61576.69211654003;1470.713210923881;1485.970280594382;1490.3144553505292;1492.500012495826;1498.3107770425727;27412.58881116745;28931.408772517712;29310.413791724473;30235.79056837702;30811.229719257197;41183.382249265684;53032;53489.465105350006;53646.73176634092;53918.460815392915;452464;511568;525086;530238;530358;26289.342766430993;26517.867410662824;26524.867375662998;26532.86733566397;27000.324991874575;44422.11155776802;44601.88495287514;44954.77522612366;45652.54347456483;46516.30225546687;37469.250614988094;37519;37877.621223787406;38000.429993549566;38550;36833;36931;36942.26115477621;37338;38021.42967855429;36900.261994759414;37046.44430333601;37396.62603373932;37412.625873740915;43296.56703433051;25983.480330393664;26741;32444.188895277806;32775.50836737498;32883.67116328806;18500.907495462976;24493.51012979695;24601.015959361426;24835.62746558767;24986.625200621642 10:03:57 INFO - PROCESS | 1942 | |2;jquery.html;395.4108378516012;397.94229836673685;397.94826672532565;401.24565816265425;407.7940639976811;19038.286554210117;20257.898710506444;21049;21128.89435552824;21207.151713931442;17582.648347033053;17897.657675674312;21114.788852111473;21627.3511794646;21883.562328753414;5784.161296611999;6123.632582045091;6208.255009398878;6209;6216.316205217422;4536.72779633223;5177.559907407875;6107.877842443148;6170.598911070789;6171.660558669271;5034.1945288753805;5964.105384192384;5985.25184351956;5995.640261584297;6000.33996260411;5919.904817608756;5923.614965027259;6085.330613632497;6096.329403765582;6133.386661333853;4385.824567017347;5871.735771890275;5934.406559344075;5949.732262048175;5957.225560677128;13901.374438150307;14159.85840141606;14183;14266.785998210034;18616.906915465337;2033.9593208135902;2120.0989579428833;2124.1715730864976;2248.932532024042;2359.3275916363928;8621.275744851055;9527.52362381878;10114.393136411838;10235.590576376935;10384;1709.2308461192388;1721.0534206186546;1737.5569229846435;1738.1830539646346;1807.41259090796;122505;125474;127804.36097819451;128494;128628;9584.37701549397;9608.807823843485;11706.180567360374;11740.530378784924;11944.343061131722;13564.846988005933;15350.769738453931;15401.845981540266;15445.613859653598;15449.841261905178;8238.917610823935;8862.069482704223;8983.865242021375;9062.909370906338;9095.86301712286;6316.021016742412;6643.069970204176;6758.209074595236;8121.918780812234;8628.309735221168;4676.883077923079;4699.882502937386;4742.288656701522;4756.643251756063;4775.140474714584;1593.82853602602;1645.1445248470727;1947.6104779044247;1956.5108722819296;2009.6181725472406;394.84601005607834;399.84006397441254;403.0910297279674;433.0883490253035;1030.253066526762;11076.446177691083;11287.830682539769;11968.281903085843;12064.396780160956;12194.329311887754;434.36148861174024;448.53800585397096;454.98862528436393;462.6576333513176;463.87475381646766;6134.171886795315;6156.78451254204;6187.81436556904;6257.937420625735;6306.621602703853;545503.272483635;580733;588040;596523;635321;134267;135647;138904;139284;140256.30533071066;467.07286037216096;469.91071696377577;475.3297850031439;476.6734786671072;478.9128677901145;11194.66416007521;11343.943280283545;11344.886551134714;11428.48571814287;11593.304401735586;51420.48579514157;51620.967580648925;52040;52062.73968630284;52530.21204681856;71480.64259678668;75102;76276.85584716123;78012;79839.60080199795;7039.859202815813;7057.50597458173;8156.8368632625825;8443.535605541754;8699.521526316114;9601.663941762004;10668.359898406121;10685.626003089854;12736.936315318364;12855.550055748005;11000.284981476178;11059.336439813636;11188.160887933274;11361.261518001304;11400.714982125513;10329.74175645615;10832.566697332019;10998;11059.281146725438;11522.596709115141;11160.497777599865;11449;11590.71023224426;11613.012893904113;11697.2981621103;6989.986451964335;7403.629818508945;8186.83626327482;8236.299914507334;8504.319654427754;8566.357523185661;10913.454327283764;11161.72095697614;11269.830952535882;11376.658700239006 10:03:57 INFO - PROCESS | 1942 | |3;mootools.html;1482.6441654003038;1625.6667383186448;1692.8826974197032;1848.7411762353265;1872.9719054214181;7656.693732250703;8740.475571465717;8793.5603219839;9073.410228335153;9095.545222738861;8762.912370876289;8813.823723525542;8941.463512189273;8943.284537237021;8961.686340978067;2043.6511902144587;2443.767842055011;2450.7304196538366;2462.91379801707;2479.590867506859;2453.2885463215734;2463.1132792194794;2498.287987923442;2509.723930367658;2510.6108553174195;2126.4264444311175;2420.4190994161363;2456.2876765738006;2464.9753502464923;2505.8621775802326;2090.8186874415983;2411.6744239527616;2470.3206618179966;2476.182859656318;2500.987495062513;2414.3481260059684;2468.222509909373;2479.3181874984343;2482.6027835546406;2486.204414587333;7559.962200188964;9053.818923621557;9124.27005839538;9126.726198214064;9153.496557689325;4704.741239231841;5469.288992430998;5494.395616482184;5527.281453411031;5559.555235581144;3514.9121271968406;3537.593176784677;3545.964540354589;3624.9456258156147;3647.471116688092;1007.8790545134558;1023.5087158164049;1028.2802038572954;1044.498640652491;1059.8887116852698;11644.53421863133;11706.356150411724;11864.822027669505;11889.524419023228;11900.642980710505;4750.7387093709485;4853.3447984522345;4876.5854902332985;4990.750462476934;4995.800167993312;10101.343412678152;10131.442770647685;10146.847797283046;10171.033751793568;10203.979602039737;5399.2980912481125;5416.079266524622;5465.781368745285;5479.0959491684225;5496.31296087989;4490.57339552742;4822.300766388924;4861.0277944411255;4999.350084489066;5011.173156429221;3474.305138972165;4050.3927149661667;4074.9796251018556;4095.7747323896874;4185.330347144503;1366.8701473360015;1371.1567386057702;1376.7884261849708;1377.7726675098697;1387.2508845223667;845;870.7939503787173;925.2505470568726;969.9903000970041;988.1650005745134;5984;6072.969635151796;6105.725242364104;6113.510919126548;6252.280987686474;449.23629829290337;450.09530843005797;468.71642656193194;470.2922102236181;472.98817529562035;4051.0682543015273;4080.10237747695;4087.652549533323;4100.097978444737;4178.373244013423;8891.71070194831;10428.27002109845;10745.785084298426;10796;11180.993710566088;9273.629054837866;10908.400037997828;10920.945395272973;10998.34009959405;11242.325460472235;8285.95857020723;8384.622691978875;8407.495550266882;8452.732090186399;8495.192956669107;4717.599004084691;5790.7973220937065;6005.099235114768;6092.147099406176;6109.419605137505;10114.645987390406;10220.540075696761;10226.07965283128;10263.486825658836;10329.070383665507;10037.49812509357;10128.088472037552;10447.164226861985;10457.111145552713;10519;6639.767608133885;6950.339717726808;7206.89189662166;7319.670614822239;7487.251274872469;8380.539070350946;8387.077421483755;8416.284615807725;8481.27909127731;8504.319654427754;7338.633068346669;7448.031755871702;7471.290227428385;7577.924220757722;7590.848183036198;7407.777766667009;7449.0316258886;7466.290702383265;7466.925330746841;7547.0943486781935;7073.893891591733;7098.254683258184;7136.536125151849;7149.14210294768;7178.497505174588;4850.126977144007;4885.4870238624435;5021.297018417352;5046.369203849519;5196.662216955886;4964.180910149785;5070.188769796815;5100.362454693163;5136.589072874236;5302.310699609026 10:03:57 INFO - PROCESS | 1942 | |4;prototype.html;266.24253997377457;286.895283221624;316.47149260734574;319.7681680781433;328.8437991953821;5862.794802181919;6058.757649694007;6110.938890611093;6209.379062093788;6357.408064443586;6220.751169953196;6270.937290627104;6276.65478398689;6292.811215663537;6308.621482711041;4703.764811759415;4704.577142771467;4721.386219791422;4726.716397016173;4746.976265118687;4215.725977811448;4507.774611269439;4606.170889239936;4669.836555720551;4703.459102203239;4185.807044992178;4230.788460576974;4543.454785425737;4620.006698559809;4672.64955128365;3767.077066118805;4318.481782186158;4666.510016448275;4672.696274742131;4714.6464015199;3909.7488803582864;3991.960080399188;4023.109138704804;4037.475128233311;4041.333180025292;4329.610335069859;5053.140966035783;5714.657120572779;5978.581499295051;6001.189839371673;5449.182622606601;5474.698891560963;5484.7531861065945;5503.9174412383445;5540.806071787468;6015.195441367572;6493.642849643268;6586.802395928082;6612.239592446834;6688.431483323923;4400.44994375703;4412.823487060514;4430.579094985971;4444.911101777979;4468.977655111704;6538.640374779386;6801.285864984205;6991.930080699128;7014.333638304404;7050.471214658921;4487.326900964881;4500.4149460569915;4519.536852415442;4534.2971839364955;4538.205813982539;4383.211022016004;4586.816527338936;4593.908121837545;4600.665806916024;4636.39726835516;4608.377868987711;5266.473352664703;5276.815311464157;5346;5448.891022179613;5000.62495312853;6121.540884433685;6347.301796802348;6561.639109849004;6582.407583317525;1407.7043820797542;1485.628592851787;1581.3358389476448;1582.327510807902;1597;1034.1261633919326;1037.7924415117006;1057.286331726082;1058.9311694739818;1061.368485750977;6086.300075491286;6156.815295541141;6175.475084617768;6181.907271390933;6191.133241346215;5961.850953726105;6562.753076915466;6651.474718399256;6846;6869.420033392248;4976.850694479172;5331.546818520469;5908.527317814564;5944.7027648617395;6170.907436388457;3574.356615809127;3618.620044895301;3619.7104231661406;3622.8188590570894;3666.3034023535442;6577.572457790228;6881.004508692423;7114.786556403316;7204.3516083551685;7216.4948453608795;7036.296370362923;7067.540609860445;7092.219855815959;7098.964505177544;7109.964450177716;6778.779819632415;7096.835465183169;7137.786576282045;7183.353498185188;7253.927460725537;5620.409856965123;6695.99560065994;6778.389944904785;6854.5201835873;6883.242843287335;5467.972660136674;6621.867562648624;6687.063811066358;6795.349813572611;6845.383915447634;6267.9373206267355;7226.566406015656;7231.457640676865;7238.457115716237;7250;3612.8193590320902;4339.652827773833;4419.18245124653;4430.446194225722;4430.667699922455;6573.671316434064;6616;6675.760891833624;6882.346177113166;6895.793126206222;5249.950009998092;6663.766768163091;6665.666716664245;6702.497312701469;6706.798796036127;6408.263049749152;6482.995135753966;6583.539152259296;6658.700358483783;6662.800115996528;6531.444827189742;6564.573302735307;6565.573237739532;6571.211454625475;6588.769393071219;6122.173506576555;6638.468922486286;6673.499487538357;6674.532782705164;6725.630090345078;4847.733374664427;5579.051561234681;6249.562530622813;6433.485321174201;6544.7420639879165 10:03:57 INFO - PROCESS | 1942 | |5;yui.html;670.5205777868823;716.8924661300807;744;762.3558093411066;774.4191856107919;12463.005919052943;14979.625509362271;15023.023503472266;15170.544883653494;15210.391584336627;13695.109817861832;13918.582442526715;14161.150330980123;14165.645858853533;14191.858081419183;731.7182884589445;898.9685361012366;914.3919293669702;927.4110939553365;934.0146145816161;896.6099746610246;897.6544030548221;899.7120921305185;926.6756635177701;934.0379409208526;977.2670497127154;979.3780949097339;980.9117179453848;981.9836469254324;982.2093214961952;962.9277804164681;967.1972263021676;974.9122578967891;976.067855198284;979.0894468144622;704.718112754897;711.9145702515731;725.8620862036196;727.9818004549928;730.3572855886786;14543.563693089225;14635.341409636592;14894;14923.030003049767;14970.62573435662;12537.122401431903;12542.12818917237;12602.810957835549;12671.493140274379;12806.911412529946;9926.553305101288;10250.79498410035;10257.640982565574;10581;10652.41411722355;3424.811635360079;3468.1156305142026;3479.8956031319103;3505.4040813061843;3579.2125732338845;83430.58284708537;83845.58077209636;85193;85532;85864.57067714684;9371.109744574254;9397.483138427453;9437.858432123523;9471.526423678788;9614.375065620712;18649.9742514163;20182.293619723237;21219.045142968604;21428;21429.142834286766;7283.854322913512;7321.084864391951;7364.705411783469;7528.661210245552;7536.5101268417375;3977.5028121484816;4052.250333688277;4077.1437998020747;4083.4691490106097;4105.261053010487;3562.127278816655;3609.4405367167606;3618.2401695643684;3671.155634204148;3705.3886108792285;655.9180102487189;656.1994366872408;664.1598378051768;666.7599664120912;669.9397054265139;511.5089514066538;524.0566979436998;565.3385538919392;576.9624974376652;585.2158108135035;6384.32964538726;7677.692892284246;7801.063872335356;7973.441859069925;8040.035195776544;508.36962166912764;578.6383510306059;584.4681339980682;613.8618810767631;618.3816183816184;3277.344531093791;3774.226283611853;3783.6027217142355;3861.4980052593546;3898.142408670088;87975;88873.66689499663;89005.55497222602;89614;90035;75200;75377.49245015069;75630;75898.48203035908;76083;67671.64656706937;67729.66135169292;67762;67862.66068669625;67916.320836791;7028.789136325919;7065.293470652894;7489.474629367307;7537.2085930976455;7624.69501219945;32621;43643.127137457705;44073.11853762843;44079.11841763082;44096.779516102215;56748;59136.81726365535;61466.234019468335;61864.38135618586;61988;7820.139784623573;8352.164783521599;8357.749267521984;9568.856467152858;9833.63992641775;9615.759606009906;9818.969008254317;10585.27550242157;10623.097036751653;10733.838992414963;10002.699919002442;10132.986701330103;10185.9490702546;10489.370637761758;10514.369137851754;10139.64511242103;10180.236482264008;10234.590616375262;10515.57937682484;10621;9942.254330925065;9971;10013.649522266976;10186.083252507013;10259.640912568024;8323.167683231628;8568.614412351584;8671.826563468821;8707.695230666895;9177.128172823572;8685.174908383693;8709.08554601783;8779.868301975348;9052.592633331647;9285.48929808867 10:03:57 INFO - PROCESS | 1942 | __end_tp_report 10:03:57 INFO - PROCESS | 1942 | __start_cc_report 10:03:57 INFO - PROCESS | 1942 | _x_x_mozilla_cycle_collect,421 10:03:57 INFO - PROCESS | 1942 | __end_cc_report 10:03:57 INFO - PROCESS | 1942 | __startTimestamp1461431037212__endTimestamp 10:03:57 INFO - PROCESS | 1942 | 10:03:57 INFO - PROCESS | 1942 | ------- Summary: start ------- 10:03:57 INFO - PROCESS | 1942 | Number of tests: 6 10:03:57 INFO - PROCESS | 1942 | 10:03:57 INFO - PROCESS | 1942 | [#0] dojo.html Cycles:165 Average:5747.61 Median:6007.22 stddev:690.82 (11.5%) stddev-sans-first:669.27 10:03:57 INFO - PROCESS | 1942 | Values: 3462.3 3468.5 3472.3 3493.7 3493.9 4694.8 5719.7 5775.9 5778.7 5819.8 5288.7 5577.3 5715.4 5817.5 5849.5 4010.6 4734.7 5215.5 5265.6 5439.2 5222.9 5381.5 5399.6 5401.4 5429.6 5199.2 5225.9 5255.5 5257.2 5326.3 5288.2 5320.3 5321.4 5322.1 5354.7 4308.8 5262.8 5263.4 5269.7 5357.4 4894.3 5648.6 5658.1 5727.2 5762.0 6141.2 6237.8 6254.2 6284.5 6295.4 6191.8 6255.7 6285.2 6303.5 6306.5 5875.0 5917.6 5919.8 5943.6 6017.8 6396.0 6427.9 6434.8 6455.7 6466.7 6126.0 6130.7 6237.4 6242.6 6253.1 6234.2 6292.6 6292.7 6300.4 6303.3 6117.0 6214.8 6229.4 6272.5 6294.9 4682.3 5589.4 6050.7 6159.6 6180.8 4942.9 4955.9 4960.4 4968.5 5010.5 4035.1 4212.4 4406.2 4457.5 4475.7 5766.4 5924.8 5930.6 5934.6 5947.2 6250.5 6268.6 6308.8 6326.7 6342.9 5968.0 5979.2 5986.2 5988.0 5994.2 5407.1 5468.8 5497.0 5500.4 5507.8 6472.4 6509.5 6534.6 6545.8 6579.8 6275.3 6296.0 6304.2 6314.3 6325.2 6376.6 6379.7 6461.4 6494.0 6505.0 5084.5 6132.9 6151.3 6208.5 6210.9 5181.6 5403.8 5515.5 6167.6 6307.3 6109.8 6119.0 6124.3 6180.1 6208.1 5096.4 6180.4 6192.9 6213.7 6214.5 6007.2 6033.4 6042.3 6117.6 6118.4 6112.4 6143.5 6188.0 6202.3 6209.8 5013.6 6099.2 6118.2 6138.5 6144.9 10:03:57 INFO - PROCESS | 1942 | 10:03:57 INFO - PROCESS | 1942 | [#1] ext.html Cycles:180 Average:68077.46 Median:39419.81 stddev:146689.55 (372.1%) stddev-sans-first:147013.32 10:03:57 INFO - PROCESS | 1942 | Values: 507.3 511.4 522.4 592.0 622.2 38375.6 39240.0 39355.8 39372.4 39467.2 42509.1 42970.4 42983.8 43003.9 43163.8 9511.5 9703.4 9726.4 9754.2 9818.3 8142.7 9571.5 9656.0 9661.7 9696.6 9562.3 9648.3 9656.1 9718.6 9754.7 9585.7 9628.3 9660.0 9661.8 9690.6 9531.5 9574.8 9635.5 9639.5 9644.7 39490.6 39636.8 39844.0 40313.0 40440.6 2807.7 2945.9 3147.5 3177.9 3185.6 26498.5 26766.9 27752.9 28955.6 29617.0 2684.8 2729.1 2735.3 2757.9 2837.6 450152.0 450435.0 452922.0 455907.0 456194.0 52211.7 62900.0 63788.4 63868.7 63934.4 72898.6 74474.0 75077.2 75168.6 75236.2 17063.9 17066.3 17488.6 17566.8 17608.0 13122.6 13300.9 13407.2 13429.4 13439.5 19814.2 19955.4 20136.4 20251.0 20379.5 6707.5 6858.9 8075.8 8114.4 8198.6 820.8 963.0 1047.1 1047.4 1170.3 23527.9 29069.9 29109.1 29719.9 30370.7 1026.1 1131.8 1135.3 1147.3 1266.1 15472.0 15546.4 16094.8 16204.3 16482.6 621998.0 668323.0 671392.0 671987.0 672624.0 58680.4 60430.7 61072.3 61079.7 61576.7 1470.7 1486.0 1490.3 1492.5 1498.3 27412.6 28931.4 29310.4 30235.8 30811.2 41183.4 53032.0 53489.5 53646.7 53918.5 452464.0 511568.0 525086.0 530238.0 530358.0 26289.3 26517.9 26524.9 26532.9 27000.3 44422.1 44601.9 44954.8 45652.5 46516.3 37469.3 37519.0 37877.6 38000.4 38550.0 36833.0 36931.0 36942.3 37338.0 38021.4 36900.3 37046.4 37396.6 37412.6 43296.6 25983.5 26741.0 32444.2 32775.5 32883.7 18500.9 24493.5 24601.0 24835.6 24986.6 10:03:57 INFO - PROCESS | 1942 | 10:03:57 INFO - PROCESS | 1942 | [#2] jquery.html Cycles:180 Average:34200.40 Median:402.17 stddev:99199.25 (24666.1%) stddev-sans-first:99445.05 10:03:57 INFO - PROCESS | 1942 | Values: 395.4 397.9 397.9 401.2 407.8 19038.3 20257.9 21049.0 21128.9 21207.2 17582.6 17897.7 21114.8 21627.4 21883.6 5784.2 6123.6 6208.3 6209.0 6216.3 4536.7 5177.6 6107.9 6170.6 6171.7 5034.2 5964.1 5985.3 5995.6 6000.3 5919.9 5923.6 6085.3 6096.3 6133.4 4385.8 5871.7 5934.4 5949.7 5957.2 13901.4 14159.9 14183.0 14266.8 18616.9 2034.0 2120.1 2124.2 2248.9 2359.3 8621.3 9527.5 10114.4 10235.6 10384.0 1709.2 1721.1 1737.6 1738.2 1807.4 122505.0 125474.0 127804.4 128494.0 128628.0 9584.4 9608.8 11706.2 11740.5 11944.3 13564.8 15350.8 15401.8 15445.6 15449.8 8238.9 8862.1 8983.9 9062.9 9095.9 6316.0 6643.1 6758.2 8121.9 8628.3 4676.9 4699.9 4742.3 4756.6 4775.1 1593.8 1645.1 1947.6 1956.5 2009.6 394.8 399.8 403.1 433.1 1030.3 11076.4 11287.8 11968.3 12064.4 12194.3 434.4 448.5 455.0 462.7 463.9 6134.2 6156.8 6187.8 6257.9 6306.6 545503.3 580733.0 588040.0 596523.0 635321.0 134267.0 135647.0 138904.0 139284.0 140256.3 467.1 469.9 475.3 476.7 478.9 11194.7 11343.9 11344.9 11428.5 11593.3 51420.5 51621.0 52040.0 52062.7 52530.2 71480.6 75102.0 76276.9 78012.0 79839.6 7039.9 7057.5 8156.8 8443.5 8699.5 9601.7 10668.4 10685.6 12736.9 12855.6 11000.3 11059.3 11188.2 11361.3 11400.7 10329.7 10832.6 10998.0 11059.3 11522.6 11160.5 11449.0 11590.7 11613.0 11697.3 6990.0 7403.6 8186.8 8236.3 8504.3 8566.4 10913.5 11161.7 11269.8 11376.7 10:03:57 INFO - PROCESS | 1942 | 10:03:57 INFO - PROCESS | 1942 | [#3] mootools.html Cycles:180 Average:5770.38 Median:2611.86 stddev:3236.47 (123.9%) stddev-sans-first:3229.51 10:03:57 INFO - PROCESS | 1942 | Values: 1482.6 1625.7 1692.9 1848.7 1873.0 7656.7 8740.5 8793.6 9073.4 9095.5 8762.9 8813.8 8941.5 8943.3 8961.7 2043.7 2443.8 2450.7 2462.9 2479.6 2453.3 2463.1 2498.3 2509.7 2510.6 2126.4 2420.4 2456.3 2465.0 2505.9 2090.8 2411.7 2470.3 2476.2 2501.0 2414.3 2468.2 2479.3 2482.6 2486.2 7560.0 9053.8 9124.3 9126.7 9153.5 4704.7 5469.3 5494.4 5527.3 5559.6 3514.9 3537.6 3546.0 3624.9 3647.5 1007.9 1023.5 1028.3 1044.5 1059.9 11644.5 11706.4 11864.8 11889.5 11900.6 4750.7 4853.3 4876.6 4990.8 4995.8 10101.3 10131.4 10146.8 10171.0 10204.0 5399.3 5416.1 5465.8 5479.1 5496.3 4490.6 4822.3 4861.0 4999.4 5011.2 3474.3 4050.4 4075.0 4095.8 4185.3 1366.9 1371.2 1376.8 1377.8 1387.3 845.0 870.8 925.3 970.0 988.2 5984.0 6073.0 6105.7 6113.5 6252.3 449.2 450.1 468.7 470.3 473.0 4051.1 4080.1 4087.7 4100.1 4178.4 8891.7 10428.3 10745.8 10796.0 11181.0 9273.6 10908.4 10920.9 10998.3 11242.3 8286.0 8384.6 8407.5 8452.7 8495.2 4717.6 5790.8 6005.1 6092.1 6109.4 10114.6 10220.5 10226.1 10263.5 10329.1 10037.5 10128.1 10447.2 10457.1 10519.0 6639.8 6950.3 7206.9 7319.7 7487.3 8380.5 8387.1 8416.3 8481.3 8504.3 7338.6 7448.0 7471.3 7577.9 7590.8 7407.8 7449.0 7466.3 7466.9 7547.1 7073.9 7098.3 7136.5 7149.1 7178.5 4850.1 4885.5 5021.3 5046.4 5196.7 4964.2 5070.2 5100.4 5136.6 5302.3 10:03:57 INFO - PROCESS | 1942 | 10:03:57 INFO - PROCESS | 1942 | [#4] prototype.html Cycles:180 Average:5355.10 Median:5989.89 stddev:1693.55 (28.3%) stddev-sans-first:1654.67 10:03:57 INFO - PROCESS | 1942 | Values: 266.2 286.9 316.5 319.8 328.8 5862.8 6058.8 6110.9 6209.4 6357.4 6220.8 6270.9 6276.7 6292.8 6308.6 4703.8 4704.6 4721.4 4726.7 4747.0 4215.7 4507.8 4606.2 4669.8 4703.5 4185.8 4230.8 4543.5 4620.0 4672.6 3767.1 4318.5 4666.5 4672.7 4714.6 3909.7 3992.0 4023.1 4037.5 4041.3 4329.6 5053.1 5714.7 5978.6 6001.2 5449.2 5474.7 5484.8 5503.9 5540.8 6015.2 6493.6 6586.8 6612.2 6688.4 4400.4 4412.8 4430.6 4444.9 4469.0 6538.6 6801.3 6991.9 7014.3 7050.5 4487.3 4500.4 4519.5 4534.3 4538.2 4383.2 4586.8 4593.9 4600.7 4636.4 4608.4 5266.5 5276.8 5346.0 5448.9 5000.6 6121.5 6347.3 6561.6 6582.4 1407.7 1485.6 1581.3 1582.3 1597.0 1034.1 1037.8 1057.3 1058.9 1061.4 6086.3 6156.8 6175.5 6181.9 6191.1 5961.9 6562.8 6651.5 6846.0 6869.4 4976.9 5331.5 5908.5 5944.7 6170.9 3574.4 3618.6 3619.7 3622.8 3666.3 6577.6 6881.0 7114.8 7204.4 7216.5 7036.3 7067.5 7092.2 7099.0 7110.0 6778.8 7096.8 7137.8 7183.4 7253.9 5620.4 6696.0 6778.4 6854.5 6883.2 5468.0 6621.9 6687.1 6795.3 6845.4 6267.9 7226.6 7231.5 7238.5 7250.0 3612.8 4339.7 4419.2 4430.4 4430.7 6573.7 6616.0 6675.8 6882.3 6895.8 5250.0 6663.8 6665.7 6702.5 6706.8 6408.3 6483.0 6583.5 6658.7 6662.8 6531.4 6564.6 6565.6 6571.2 6588.8 6122.2 6638.5 6673.5 6674.5 6725.6 4847.7 5579.1 6249.6 6433.5 6544.7 10:03:57 INFO - PROCESS | 1942 | 10:03:57 INFO - PROCESS | 1942 | [#5] yui.html Cycles:180 Average:17422.19 Median:670.23 stddev:24901.53 (3715.4%) stddev-sans-first:24939.61 10:03:57 INFO - PROCESS | 1942 | Values: 670.5 716.9 744.0 762.4 774.4 12463.0 14979.6 15023.0 15170.5 15210.4 13695.1 13918.6 14161.2 14165.6 14191.9 731.7 899.0 914.4 927.4 934.0 896.6 897.7 899.7 926.7 934.0 977.3 979.4 980.9 982.0 982.2 962.9 967.2 974.9 976.1 979.1 704.7 711.9 725.9 728.0 730.4 14543.6 14635.3 14894.0 14923.0 14970.6 12537.1 12542.1 12602.8 12671.5 12806.9 9926.6 10250.8 10257.6 10581.0 10652.4 3424.8 3468.1 3479.9 3505.4 3579.2 83430.6 83845.6 85193.0 85532.0 85864.6 9371.1 9397.5 9437.9 9471.5 9614.4 18650.0 20182.3 21219.0 21428.0 21429.1 7283.9 7321.1 7364.7 7528.7 7536.5 3977.5 4052.3 4077.1 4083.5 4105.3 3562.1 3609.4 3618.2 3671.2 3705.4 655.9 656.2 664.2 666.8 669.9 511.5 524.1 565.3 577.0 585.2 6384.3 7677.7 7801.1 7973.4 8040.0 508.4 578.6 584.5 613.9 618.4 3277.3 3774.2 3783.6 3861.5 3898.1 87975.0 88873.7 89005.6 89614.0 90035.0 75200.0 75377.5 75630.0 75898.5 76083.0 67671.6 67729.7 67762.0 67862.7 67916.3 7028.8 7065.3 7489.5 7537.2 7624.7 32621.0 43643.1 44073.1 44079.1 44096.8 56748.0 59136.8 61466.2 61864.4 61988.0 7820.1 8352.2 8357.7 9568.9 9833.6 9615.8 9819.0 10585.3 10623.1 10733.8 10002.7 10133.0 10185.9 10489.4 10514.4 10139.6 10180.2 10234.6 10515.6 10621.0 9942.3 9971.0 10013.6 10186.1 10259.6 8323.2 8568.6 8671.8 8707.7 9177.1 8685.2 8709.1 8779.9 9052.6 9285.5 10:03:57 INFO - PROCESS | 1942 | -------- Summary: end -------- 10:03:57 INFO - PROCESS | 1942 | 10:03:57 INFO - TEST-INFO | 1942: exit 0 10:03:57 INFO - TEST-OK | dromaeo_css | took 1111921ms 10:03:57 INFO - TEST-START | kraken 10:03:57 INFO - operating with platform_type : mac_ 10:03:57 INFO - Initialising browser for kraken test... 10:03:57 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/tmpKtQMBL/profile http://localhost:51167/getInfo.html) 10:04:00 INFO - PROCESS | 1946 | __metrics Screen width/height:1600/1200 10:04:00 INFO - PROCESS | 1946 | colorDepth:24 10:04:00 INFO - PROCESS | 1946 | Browser inner width/height: 1024/689 10:04:00 INFO - PROCESS | 1946 | __metrics 10:04:01 ERROR - PROCESS | 1946 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 10:04:01 INFO - TEST-INFO | 1946: exit 0 10:04:01 INFO - Browser initialized. 10:04:01 INFO - Running cycle 1/1 for kraken test... 10:04:01 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/tmpKtQMBL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:04:13 INFO - PROCESS | 1949 | RSS: Main: 249884672 10:04:13 INFO - PROCESS | 1949 | 10:04:16 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51167/tests/kraken/driver.html?testName=audio-beat-detection) 10:04:17 INFO - PROCESS | 1949 | RSS: Main: 261734400 10:04:17 INFO - PROCESS | 1949 | 10:04:20 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51167/tests/kraken/driver.html?testName=audio-dft) 10:04:20 INFO - PROCESS | 1949 | RSS: Main: 270901248 10:04:20 INFO - PROCESS | 1949 | 10:04:24 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51167/tests/kraken/driver.html?testName=audio-fft) 10:04:25 INFO - PROCESS | 1949 | RSS: Main: 274022400 10:04:25 INFO - PROCESS | 1949 | 10:04:27 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51167/tests/kraken/driver.html?testName=audio-oscillator) 10:04:27 INFO - PROCESS | 1949 | RSS: Main: 275316736 10:04:27 INFO - PROCESS | 1949 | 10:04:30 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51167/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:04:30 INFO - PROCESS | 1949 | RSS: Main: 275746816 10:04:30 INFO - PROCESS | 1949 | 10:04:33 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51167/tests/kraken/driver.html?testName=imaging-darkroom) 10:04:34 INFO - PROCESS | 1949 | RSS: Main: 276713472 10:04:34 INFO - PROCESS | 1949 | 10:04:37 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51167/tests/kraken/driver.html?testName=imaging-desaturate) 10:04:37 INFO - PROCESS | 1949 | RSS: Main: 277057536 10:04:37 INFO - PROCESS | 1949 | 10:04:40 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51167/tests/kraken/driver.html?testName=json-parse-financial) 10:04:41 INFO - PROCESS | 1949 | RSS: Main: 277061632 10:04:41 INFO - PROCESS | 1949 | 10:04:43 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51167/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:04:43 INFO - PROCESS | 1949 | RSS: Main: 276832256 10:04:43 INFO - PROCESS | 1949 | 10:04:45 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51167/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:04:46 INFO - PROCESS | 1949 | RSS: Main: 277295104 10:04:46 INFO - PROCESS | 1949 | 10:04:48 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51167/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:04:49 INFO - PROCESS | 1949 | RSS: Main: 276762624 10:04:49 INFO - PROCESS | 1949 | 10:04:52 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51167/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:04:52 INFO - PROCESS | 1949 | RSS: Main: 276791296 10:04:52 INFO - PROCESS | 1949 | 10:04:55 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51167/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:04:56 INFO - PROCESS | 1949 | RSS: Main: 276774912 10:04:56 INFO - PROCESS | 1949 | 10:04:58 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51167/tests/kraken/driver.html?testName=ai-astar) 10:04:58 INFO - PROCESS | 1949 | RSS: Main: 278540288 10:04:58 INFO - PROCESS | 1949 | 10:04:58 INFO - PROCESS | 1949 | __start_tp_report 10:04:58 INFO - PROCESS | 1949 | _x_x_mozilla_page_load 10:04:58 INFO - PROCESS | 1949 | _x_x_mozilla_page_load_details 10:04:58 INFO - PROCESS | 1949 | |i|pagename|runs| 10:04:58 INFO - PROCESS | 1949 | |0;ai-astar;116;107;113;115;132;131;96;169;171;106 10:04:58 INFO - PROCESS | 1949 | |1;audio-beat-detection;167;156;154;204;160;146;124;161;162;136 10:04:58 INFO - PROCESS | 1949 | |2;audio-dft;205;217;270;286;193;283;274;197;197;195 10:04:58 INFO - PROCESS | 1949 | |3;audio-fft;86;113;72;155;62;55;57;62;133;46 10:04:58 INFO - PROCESS | 1949 | |4;audio-oscillator;90;124;145;62;96;121;165;120;84;119 10:04:58 INFO - PROCESS | 1949 | |5;imaging-gaussian-blur;94;87;88;87;89;87;89;87;89;86 10:04:58 INFO - PROCESS | 1949 | |6;imaging-darkroom;119;106;151;152;114;95;97;96;96;95 10:04:58 INFO - PROCESS | 1949 | |7;imaging-desaturate;111;91;90;91;91;90;130;150;91;89 10:04:58 INFO - PROCESS | 1949 | |8;json-parse-financial;59;62;62;61;51;51;51;77;66;83 10:04:58 INFO - PROCESS | 1949 | |9;json-stringify-tinderbox;65;66;66;75;70;71;69;66;66;65 10:04:58 INFO - PROCESS | 1949 | |10;stanford-crypto-aes;85;83;84;85;84;82;85;88;99;108 10:04:58 INFO - PROCESS | 1949 | |11;stanford-crypto-ccm;159;154;166;149;163;159;164;162;253;182 10:04:58 INFO - PROCESS | 1949 | |12;stanford-crypto-pbkdf2;180;189;170;172;161;232;189;166;182;195 10:04:58 INFO - PROCESS | 1949 | |13;stanford-crypto-sha256-iterative;72;64;64;84;82;100;99;69;70;71 10:04:58 INFO - PROCESS | 1949 | __end_tp_report 10:04:58 INFO - PROCESS | 1949 | __start_cc_report 10:04:58 INFO - PROCESS | 1949 | _x_x_mozilla_cycle_collect,1092 10:04:58 INFO - PROCESS | 1949 | __end_cc_report 10:04:58 INFO - PROCESS | 1949 | __startTimestamp1461431098445__endTimestamp 10:04:58 INFO - PROCESS | 1949 | 10:04:58 INFO - PROCESS | 1949 | ------- Summary: start ------- 10:04:58 INFO - PROCESS | 1949 | Number of tests: 14 10:04:58 INFO - PROCESS | 1949 | 10:04:58 INFO - PROCESS | 1949 | [#0] ai-astar Cycles:10 Average:125.60 Median:131.50 stddev:25.79 (19.6%) stddev-sans-first:27.12 10:04:58 INFO - PROCESS | 1949 | Values: 116.0 107.0 113.0 115.0 132.0 131.0 96.0 169.0 171.0 106.0 10:04:58 INFO - PROCESS | 1949 | 10:04:58 INFO - PROCESS | 1949 | [#1] audio-beat-detection Cycles:10 Average:157.00 Median:160.50 stddev:21.13 (13.2%) stddev-sans-first:22.10 10:04:58 INFO - PROCESS | 1949 | Values: 167.0 156.0 154.0 204.0 160.0 146.0 124.0 161.0 162.0 136.0 10:04:58 INFO - PROCESS | 1949 | 10:04:58 INFO - PROCESS | 1949 | [#2] audio-dft Cycles:10 Average:231.70 Median:243.50 stddev:40.85 (16.8%) stddev-sans-first:42.17 10:04:58 INFO - PROCESS | 1949 | Values: 205.0 217.0 270.0 286.0 193.0 283.0 274.0 197.0 197.0 195.0 10:04:58 INFO - PROCESS | 1949 | 10:04:58 INFO - PROCESS | 1949 | [#3] audio-fft Cycles:10 Average:84.10 Median:59.50 stddev:37.14 (62.4%) stddev-sans-first:39.38 10:04:58 INFO - PROCESS | 1949 | Values: 86.0 113.0 72.0 155.0 62.0 55.0 57.0 62.0 133.0 46.0 10:04:58 INFO - PROCESS | 1949 | 10:04:58 INFO - PROCESS | 1949 | [#4] audio-oscillator Cycles:10 Average:112.60 Median:113.50 stddev:30.29 (26.7%) stddev-sans-first:31.00 10:04:58 INFO - PROCESS | 1949 | Values: 90.0 124.0 145.0 62.0 96.0 121.0 165.0 120.0 84.0 119.0 10:04:58 INFO - PROCESS | 1949 | 10:04:58 INFO - PROCESS | 1949 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:88.50 stddev:2.26 (2.6%) stddev-sans-first:1.12 10:04:58 INFO - PROCESS | 1949 | Values: 94.0 87.0 88.0 87.0 89.0 87.0 89.0 87.0 89.0 86.0 10:04:58 INFO - PROCESS | 1949 | 10:04:58 INFO - PROCESS | 1949 | [#6] imaging-darkroom Cycles:10 Average:112.10 Median:95.00 stddev:22.42 (23.6%) stddev-sans-first:23.64 10:04:58 INFO - PROCESS | 1949 | Values: 119.0 106.0 151.0 152.0 114.0 95.0 97.0 96.0 96.0 95.0 10:04:58 INFO - PROCESS | 1949 | 10:04:58 INFO - PROCESS | 1949 | [#7] imaging-desaturate Cycles:10 Average:102.40 Median:90.50 stddev:21.37 (23.6%) stddev-sans-first:22.43 10:04:58 INFO - PROCESS | 1949 | Values: 111.0 91.0 90.0 91.0 91.0 90.0 130.0 150.0 91.0 89.0 10:04:58 INFO - PROCESS | 1949 | 10:04:58 INFO - PROCESS | 1949 | [#8] json-parse-financial Cycles:10 Average:62.30 Median:62.00 stddev:10.82 (17.5%) stddev-sans-first:11.41 10:04:58 INFO - PROCESS | 1949 | Values: 59.0 62.0 62.0 61.0 51.0 51.0 51.0 77.0 66.0 83.0 10:04:58 INFO - PROCESS | 1949 | 10:04:58 INFO - PROCESS | 1949 | [#9] json-stringify-tinderbox Cycles:10 Average:67.90 Median:67.50 stddev:3.28 (4.9%) stddev-sans-first:3.31 10:04:58 INFO - PROCESS | 1949 | Values: 65.0 66.0 66.0 75.0 70.0 71.0 69.0 66.0 66.0 65.0 10:04:58 INFO - PROCESS | 1949 | 10:04:58 INFO - PROCESS | 1949 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:85.00 stddev:8.43 (9.9%) stddev-sans-first:8.86 10:04:58 INFO - PROCESS | 1949 | Values: 85.0 83.0 84.0 85.0 84.0 82.0 85.0 88.0 99.0 108.0 10:04:58 INFO - PROCESS | 1949 | 10:04:58 INFO - PROCESS | 1949 | [#11] stanford-crypto-ccm Cycles:10 Average:171.10 Median:163.50 stddev:30.05 (18.4%) stddev-sans-first:31.55 10:04:58 INFO - PROCESS | 1949 | Values: 159.0 154.0 166.0 149.0 163.0 159.0 164.0 162.0 253.0 182.0 10:04:58 INFO - PROCESS | 1949 | 10:04:58 INFO - PROCESS | 1949 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.60 Median:185.50 stddev:20.24 (10.9%) stddev-sans-first:21.42 10:04:58 INFO - PROCESS | 1949 | Values: 180.0 189.0 170.0 172.0 161.0 232.0 189.0 166.0 182.0 195.0 10:04:58 INFO - PROCESS | 1949 | 10:04:58 INFO - PROCESS | 1949 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.50 Median:71.50 stddev:13.32 (18.6%) stddev-sans-first:13.98 10:04:58 INFO - PROCESS | 1949 | Values: 72.0 64.0 64.0 84.0 82.0 100.0 99.0 69.0 70.0 71.0 10:04:58 INFO - PROCESS | 1949 | -------- Summary: end -------- 10:04:58 INFO - PROCESS | 1949 | 10:04:59 INFO - TEST-INFO | 1949: exit 0 10:04:59 INFO - TEST-OK | kraken | took 61458ms 10:04:59 INFO - SUITE-END | took 1173s 10:04:59 INFO - Completed test suite (00:19:33) 10:04:59 INFO - javascript benchmark 10:04:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3462.2902305027465, 3468.5317482139903, 3472.3228970350774, 3493.6855682988526, 3493.947590786137, 4694.788734506947, 5719.742611582473, 5775.884482310351, 5778.739956701948, 5819.767209311628, 5288.682679039261, 5577.3028371453565, 5715.399883012286, 5817.534597232222, 5849.473547380735, 4010.6189911958463, 4734.697958061526, 5215.530602245806, 5265.578753699695, 5439.238506609058, 5222.92165617516, 5381.461853814607, 5399.568034557226, 5401.378841433247, 5429.592780541456, 5199.220116982445, 5225.869353266161, 5255.474452554752, 5257.23770053342, 5326.280952071479, 5288.206768984644, 5320.281761962163, 5321.36143662763, 5322.121849894763, 5354.705491197982, 4308.849190278325, 5262.789488420497, 5263.394709608406, 5269.7365131743645, 5357.357117145929, 4894.339264199323, 5648.63283886546, 5658.122990936411, 5727.2268243787385, 5761.971190144022, 6141.232345956755, 6237.812865614039, 6254.155688981975, 6284.465820405271, 6295.401936815995, 6191.845203869894, 6255.749770009194, 6285.151504546873, 6303.5272354573135, 6306.527010474187, 5874.970625146847, 5917.644941303535, 5919.763209471573, 5943.643381397087, 6017.819465416044, 6396.02731222843, 6427.903581446282, 6434.8391290218115, 6455.7417703292285, 6466.6766661666725, 6125.9693701531205, 6130.673316708229, 6237.407446292686, 6242.625442473466, 6253.093301471352, 6234.220722409699, 6292.622442653455, 6292.716827742763, 6300.369963003663, 6303.338149494329, 6116.969415152985, 6214.844628884223, 6229.3770622937345, 6272.498200143978, 6294.905576416358, 4682.274247491644, 5589.35722391922, 6050.727717252734, 6159.630422174594, 6180.845478862974, 4942.901141977212, 4955.900881982412, 4960.404751429852, 4968.527989841032, 5010.473900240495, 4035.112275299488, 4212.368144778307, 4406.184855801687, 4457.487388950248, 4475.686701930898, 5766.394528574523, 5924.763009479572, 5930.644161350247, 5934.643921364732, 5947.167396564484, 6250.499960003189, 6268.592541484789, 6308.8107356778455, 6326.68366581669, 6342.904856427157, 5967.9701601491715, 5979.229520765428, 5986.161937328778, 5987.970060149672, 5994.1608174855555, 5407.053765591006, 5468.808591699271, 5497.038018346853, 5500.42245564218, 5507.807226747044, 6472.352764723584, 6509.479241660656, 6534.640594767143, 6545.803625891231, 6579.835504112435, 6275.278342990615, 6296.0, 6304.243490781136, 6314.305426403, 6325.177726895474, 6376.649284289409, 6379.7129129190225, 6461.353864613689, 6494.0258961156205, 6504.959206526933, 5084.51697088776, 6132.8773424532155, 6151.292601350723, 6208.534359923115, 6210.875782484415, 5181.585473162063, 5403.757135858696, 5515.4760297771645, 6167.599106058092, 6307.3377295383325, 6109.75560977556, 6119.015143218386, 6124.2588809726185, 6180.103884936749, 6208.1308616792785, 5096.388433388017, 6180.412860778219, 6192.938070619236, 6213.720382582705, 6214.471769899428, 6007.219061521974, 6033.42682445167, 6042.274927008787, 6117.602355846856, 6118.357572455006, 6112.388761123852, 6143.477804386677, 6187.969060154671, 6202.255729312511, 6209.844753881189, 5013.598912086952, 6099.237595300588, 6118.235220597426, 6138.508919286357, 6144.907826382697], "value": 5705.478505132168, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [507.3277906773526, 511.40166005773244, 522.417504482502, 592.034982977746, 622.2315440431067, 38375.616243837554, 39240.018999525026, 39355.80322098389, 39372.40941385878, 39467.21065578687, 42509.14981700364, 42970.355444668276, 42983.78508107455, 43003.92490187747, 43163.78418107905, 9511.524423778801, 9703.369280996749, 9726.367786093917, 9754.219662426989, 9818.312718109735, 8142.674293028272, 9571.473568953705, 9655.951720241388, 9661.661841835537, 9696.612135514571, 9562.330636855422, 9648.276379271547, 9656.082672146134, 9718.56266468007, 9754.707358779213, 9585.712428627152, 9628.3260171788, 9659.951700241489, 9661.806763864683, 9690.612375504972, 9531.475768832712, 9574.808503829885, 9635.518224088837, 9639.518024098767, 9644.662436814679, 39490.60509394927, 39636.80181599102, 39844.0, 40313.0, 40440.595594043974, 2807.7239683027674, 2945.892083851536, 3147.4806656901815, 3177.8887738929025, 3185.6336521300113, 26498.470030599474, 26766.866165669242, 27752.861235693694, 28955.56566651502, 29617.0, 2684.7717943974785, 2729.140320798962, 2735.3435175557725, 2757.8758955847034, 2837.645294338208, 450152.0, 450435.0, 452922.0, 455907.0, 456194.0, 52211.73894130505, 62900.0, 63788.36211637917, 63868.68065659642, 63934.36065639377, 72898.63550682213, 74474.0, 75077.24922750703, 75168.62415687887, 75236.24763752402, 17063.91468042652, 17066.31734730597, 17488.562785930455, 17566.824331756776, 17608.031558264418, 13122.60632181036, 13300.933495332461, 13407.195568265935, 13429.39567719455, 13439.462421503225, 19814.20743170257, 19955.401337959884, 20136.39590812278, 20251.0, 20379.4905127373, 6707.49693772969, 6858.862822743517, 8075.757727268191, 8114.43198976066, 8198.590070496452, 820.7948012996751, 962.9984815791498, 1047.1413440978477, 1047.3872784421199, 1170.3329102411544, 23527.882360588086, 29069.85465072661, 29109.126726197825, 29719.851400742857, 30370.696293036788, 1026.0867827633413, 1131.7510147767478, 1135.256407053365, 1147.300146910384, 1266.0884163402336, 15471.96380687013, 15546.378144874305, 16094.75857862133, 16204.270807813677, 16482.587935301475, 621998.0, 668323.0, 671392.0, 671987.0, 672624.0, 58680.41319586749, 60430.697846511364, 61072.25174867141, 61079.69460152671, 61576.69211654003, 1470.713210923881, 1485.970280594382, 1490.3144553505292, 1492.500012495826, 1498.3107770425727, 27412.58881116745, 28931.408772517712, 29310.413791724473, 30235.79056837702, 30811.229719257197, 41183.382249265684, 53032.0, 53489.465105350006, 53646.73176634092, 53918.460815392915, 452464.0, 511568.0, 525086.0, 530238.0, 530358.0, 26289.342766430993, 26517.867410662824, 26524.867375662998, 26532.86733566397, 27000.324991874575, 44422.11155776802, 44601.88495287514, 44954.77522612366, 45652.54347456483, 46516.30225546687, 37469.250614988094, 37519.0, 37877.621223787406, 38000.429993549566, 38550.0, 36833.0, 36931.0, 36942.26115477621, 37338.0, 38021.42967855429, 36900.261994759414, 37046.44430333601, 37396.62603373932, 37412.625873740915, 43296.56703433051, 25983.480330393664, 26741.0, 32444.188895277806, 32775.50836737498, 32883.67116328806, 18500.907495462976, 24493.51012979695, 24601.015959361426, 24835.62746558767, 24986.625200621642], "value": 19893.66542228638, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.4108378516012, 397.94229836673685, 397.94826672532565, 401.24565816265425, 407.7940639976811, 19038.286554210117, 20257.898710506444, 21049.0, 21128.89435552824, 21207.151713931442, 17582.648347033053, 17897.657675674312, 21114.788852111473, 21627.3511794646, 21883.562328753414, 5784.161296611999, 6123.632582045091, 6208.255009398878, 6209.0, 6216.316205217422, 4536.72779633223, 5177.559907407875, 6107.877842443148, 6170.598911070789, 6171.660558669271, 5034.1945288753805, 5964.105384192384, 5985.25184351956, 5995.640261584297, 6000.33996260411, 5919.904817608756, 5923.614965027259, 6085.330613632497, 6096.329403765582, 6133.386661333853, 4385.824567017347, 5871.735771890275, 5934.406559344075, 5949.732262048175, 5957.225560677128, 13901.374438150307, 14159.85840141606, 14183.0, 14266.785998210034, 18616.906915465337, 2033.9593208135902, 2120.0989579428833, 2124.1715730864976, 2248.932532024042, 2359.3275916363928, 8621.275744851055, 9527.52362381878, 10114.393136411838, 10235.590576376935, 10384.0, 1709.2308461192388, 1721.0534206186546, 1737.5569229846435, 1738.1830539646346, 1807.41259090796, 122505.0, 125474.0, 127804.36097819451, 128494.0, 128628.0, 9584.37701549397, 9608.807823843485, 11706.180567360374, 11740.530378784924, 11944.343061131722, 13564.846988005933, 15350.769738453931, 15401.845981540266, 15445.613859653598, 15449.841261905178, 8238.917610823935, 8862.069482704223, 8983.865242021375, 9062.909370906338, 9095.86301712286, 6316.021016742412, 6643.069970204176, 6758.209074595236, 8121.918780812234, 8628.309735221168, 4676.883077923079, 4699.882502937386, 4742.288656701522, 4756.643251756063, 4775.140474714584, 1593.82853602602, 1645.1445248470727, 1947.6104779044247, 1956.5108722819296, 2009.6181725472406, 394.84601005607834, 399.84006397441254, 403.0910297279674, 433.0883490253035, 1030.253066526762, 11076.446177691083, 11287.830682539769, 11968.281903085843, 12064.396780160956, 12194.329311887754, 434.36148861174024, 448.53800585397096, 454.98862528436393, 462.6576333513176, 463.87475381646766, 6134.171886795315, 6156.78451254204, 6187.81436556904, 6257.937420625735, 6306.621602703853, 545503.272483635, 580733.0, 588040.0, 596523.0, 635321.0, 134267.0, 135647.0, 138904.0, 139284.0, 140256.30533071066, 467.07286037216096, 469.91071696377577, 475.3297850031439, 476.6734786671072, 478.9128677901145, 11194.66416007521, 11343.943280283545, 11344.886551134714, 11428.48571814287, 11593.304401735586, 51420.48579514157, 51620.967580648925, 52040.0, 52062.73968630284, 52530.21204681856, 71480.64259678668, 75102.0, 76276.85584716123, 78012.0, 79839.60080199795, 7039.859202815813, 7057.50597458173, 8156.8368632625825, 8443.535605541754, 8699.521526316114, 9601.663941762004, 10668.359898406121, 10685.626003089854, 12736.936315318364, 12855.550055748005, 11000.284981476178, 11059.336439813636, 11188.160887933274, 11361.261518001304, 11400.714982125513, 10329.74175645615, 10832.566697332019, 10998.0, 11059.281146725438, 11522.596709115141, 11160.497777599865, 11449.0, 11590.71023224426, 11613.012893904113, 11697.2981621103, 6989.986451964335, 7403.629818508945, 8186.83626327482, 8236.299914507334, 8504.319654427754, 8566.357523185661, 10913.454327283764, 11161.72095697614, 11269.830952535882, 11376.658700239006], "value": 8449.297716067602, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1482.6441654003038, 1625.6667383186448, 1692.8826974197032, 1848.7411762353265, 1872.9719054214181, 7656.693732250703, 8740.475571465717, 8793.5603219839, 9073.410228335153, 9095.545222738861, 8762.912370876289, 8813.823723525542, 8941.463512189273, 8943.284537237021, 8961.686340978067, 2043.6511902144587, 2443.767842055011, 2450.7304196538366, 2462.91379801707, 2479.590867506859, 2453.2885463215734, 2463.1132792194794, 2498.287987923442, 2509.723930367658, 2510.6108553174195, 2126.4264444311175, 2420.4190994161363, 2456.2876765738006, 2464.9753502464923, 2505.8621775802326, 2090.8186874415983, 2411.6744239527616, 2470.3206618179966, 2476.182859656318, 2500.987495062513, 2414.3481260059684, 2468.222509909373, 2479.3181874984343, 2482.6027835546406, 2486.204414587333, 7559.962200188964, 9053.818923621557, 9124.27005839538, 9126.726198214064, 9153.496557689325, 4704.741239231841, 5469.288992430998, 5494.395616482184, 5527.281453411031, 5559.555235581144, 3514.9121271968406, 3537.593176784677, 3545.964540354589, 3624.9456258156147, 3647.471116688092, 1007.8790545134558, 1023.5087158164049, 1028.2802038572954, 1044.498640652491, 1059.8887116852698, 11644.53421863133, 11706.356150411724, 11864.822027669505, 11889.524419023228, 11900.642980710505, 4750.7387093709485, 4853.3447984522345, 4876.5854902332985, 4990.750462476934, 4995.800167993312, 10101.343412678152, 10131.442770647685, 10146.847797283046, 10171.033751793568, 10203.979602039737, 5399.2980912481125, 5416.079266524622, 5465.781368745285, 5479.0959491684225, 5496.31296087989, 4490.57339552742, 4822.300766388924, 4861.0277944411255, 4999.350084489066, 5011.173156429221, 3474.305138972165, 4050.3927149661667, 4074.9796251018556, 4095.7747323896874, 4185.330347144503, 1366.8701473360015, 1371.1567386057702, 1376.7884261849708, 1377.7726675098697, 1387.2508845223667, 845.0, 870.7939503787173, 925.2505470568726, 969.9903000970041, 988.1650005745134, 5984.0, 6072.969635151796, 6105.725242364104, 6113.510919126548, 6252.280987686474, 449.23629829290337, 450.09530843005797, 468.71642656193194, 470.2922102236181, 472.98817529562035, 4051.0682543015273, 4080.10237747695, 4087.652549533323, 4100.097978444737, 4178.373244013423, 8891.71070194831, 10428.27002109845, 10745.785084298426, 10796.0, 11180.993710566088, 9273.629054837866, 10908.400037997828, 10920.945395272973, 10998.34009959405, 11242.325460472235, 8285.95857020723, 8384.622691978875, 8407.495550266882, 8452.732090186399, 8495.192956669107, 4717.599004084691, 5790.7973220937065, 6005.099235114768, 6092.147099406176, 6109.419605137505, 10114.645987390406, 10220.540075696761, 10226.07965283128, 10263.486825658836, 10329.070383665507, 10037.49812509357, 10128.088472037552, 10447.164226861985, 10457.111145552713, 10519.0, 6639.767608133885, 6950.339717726808, 7206.89189662166, 7319.670614822239, 7487.251274872469, 8380.539070350946, 8387.077421483755, 8416.284615807725, 8481.27909127731, 8504.319654427754, 7338.633068346669, 7448.031755871702, 7471.290227428385, 7577.924220757722, 7590.848183036198, 7407.777766667009, 7449.0316258886, 7466.290702383265, 7466.925330746841, 7547.0943486781935, 7073.893891591733, 7098.254683258184, 7136.536125151849, 7149.14210294768, 7178.497505174588, 4850.126977144007, 4885.4870238624435, 5021.297018417352, 5046.369203849519, 5196.662216955886, 4964.180910149785, 5070.188769796815, 5100.362454693163, 5136.589072874236, 5302.310699609026], "value": 4575.2991495567785, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [266.24253997377457, 286.895283221624, 316.47149260734574, 319.7681680781433, 328.8437991953821, 5862.794802181919, 6058.757649694007, 6110.938890611093, 6209.379062093788, 6357.408064443586, 6220.751169953196, 6270.937290627104, 6276.65478398689, 6292.811215663537, 6308.621482711041, 4703.764811759415, 4704.577142771467, 4721.386219791422, 4726.716397016173, 4746.976265118687, 4215.725977811448, 4507.774611269439, 4606.170889239936, 4669.836555720551, 4703.459102203239, 4185.807044992178, 4230.788460576974, 4543.454785425737, 4620.006698559809, 4672.64955128365, 3767.077066118805, 4318.481782186158, 4666.510016448275, 4672.696274742131, 4714.6464015199, 3909.7488803582864, 3991.960080399188, 4023.109138704804, 4037.475128233311, 4041.333180025292, 4329.610335069859, 5053.140966035783, 5714.657120572779, 5978.581499295051, 6001.189839371673, 5449.182622606601, 5474.698891560963, 5484.7531861065945, 5503.9174412383445, 5540.806071787468, 6015.195441367572, 6493.642849643268, 6586.802395928082, 6612.239592446834, 6688.431483323923, 4400.44994375703, 4412.823487060514, 4430.579094985971, 4444.911101777979, 4468.977655111704, 6538.640374779386, 6801.285864984205, 6991.930080699128, 7014.333638304404, 7050.471214658921, 4487.326900964881, 4500.4149460569915, 4519.536852415442, 4534.2971839364955, 4538.205813982539, 4383.211022016004, 4586.816527338936, 4593.908121837545, 4600.665806916024, 4636.39726835516, 4608.377868987711, 5266.473352664703, 5276.815311464157, 5346.0, 5448.891022179613, 5000.62495312853, 6121.540884433685, 6347.301796802348, 6561.639109849004, 6582.407583317525, 1407.7043820797542, 1485.628592851787, 1581.3358389476448, 1582.327510807902, 1597.0, 1034.1261633919326, 1037.7924415117006, 1057.286331726082, 1058.9311694739818, 1061.368485750977, 6086.300075491286, 6156.815295541141, 6175.475084617768, 6181.907271390933, 6191.133241346215, 5961.850953726105, 6562.753076915466, 6651.474718399256, 6846.0, 6869.420033392248, 4976.850694479172, 5331.546818520469, 5908.527317814564, 5944.7027648617395, 6170.907436388457, 3574.356615809127, 3618.620044895301, 3619.7104231661406, 3622.8188590570894, 3666.3034023535442, 6577.572457790228, 6881.004508692423, 7114.786556403316, 7204.3516083551685, 7216.4948453608795, 7036.296370362923, 7067.540609860445, 7092.219855815959, 7098.964505177544, 7109.964450177716, 6778.779819632415, 7096.835465183169, 7137.786576282045, 7183.353498185188, 7253.927460725537, 5620.409856965123, 6695.99560065994, 6778.389944904785, 6854.5201835873, 6883.242843287335, 5467.972660136674, 6621.867562648624, 6687.063811066358, 6795.349813572611, 6845.383915447634, 6267.9373206267355, 7226.566406015656, 7231.457640676865, 7238.457115716237, 7250.0, 3612.8193590320902, 4339.652827773833, 4419.18245124653, 4430.446194225722, 4430.667699922455, 6573.671316434064, 6616.0, 6675.760891833624, 6882.346177113166, 6895.793126206222, 5249.950009998092, 6663.766768163091, 6665.666716664245, 6702.497312701469, 6706.798796036127, 6408.263049749152, 6482.995135753966, 6583.539152259296, 6658.700358483783, 6662.800115996528, 6531.444827189742, 6564.573302735307, 6565.573237739532, 6571.211454625475, 6588.769393071219, 6122.173506576555, 6638.468922486286, 6673.499487538357, 6674.532782705164, 6725.630090345078, 4847.733374664427, 5579.051561234681, 6249.562530622813, 6433.485321174201, 6544.7420639879165], "value": 4797.192413031272, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [670.5205777868823, 716.8924661300807, 744.0, 762.3558093411066, 774.4191856107919, 12463.005919052943, 14979.625509362271, 15023.023503472266, 15170.544883653494, 15210.391584336627, 13695.109817861832, 13918.582442526715, 14161.150330980123, 14165.645858853533, 14191.858081419183, 731.7182884589445, 898.9685361012366, 914.3919293669702, 927.4110939553365, 934.0146145816161, 896.6099746610246, 897.6544030548221, 899.7120921305185, 926.6756635177701, 934.0379409208526, 977.2670497127154, 979.3780949097339, 980.9117179453848, 981.9836469254324, 982.2093214961952, 962.9277804164681, 967.1972263021676, 974.9122578967891, 976.067855198284, 979.0894468144622, 704.718112754897, 711.9145702515731, 725.8620862036196, 727.9818004549928, 730.3572855886786, 14543.563693089225, 14635.341409636592, 14894.0, 14923.030003049767, 14970.62573435662, 12537.122401431903, 12542.12818917237, 12602.810957835549, 12671.493140274379, 12806.911412529946, 9926.553305101288, 10250.79498410035, 10257.640982565574, 10581.0, 10652.41411722355, 3424.811635360079, 3468.1156305142026, 3479.8956031319103, 3505.4040813061843, 3579.2125732338845, 83430.58284708537, 83845.58077209636, 85193.0, 85532.0, 85864.57067714684, 9371.109744574254, 9397.483138427453, 9437.858432123523, 9471.526423678788, 9614.375065620712, 18649.9742514163, 20182.293619723237, 21219.045142968604, 21428.0, 21429.142834286766, 7283.854322913512, 7321.084864391951, 7364.705411783469, 7528.661210245552, 7536.5101268417375, 3977.5028121484816, 4052.250333688277, 4077.1437998020747, 4083.4691490106097, 4105.261053010487, 3562.127278816655, 3609.4405367167606, 3618.2401695643684, 3671.155634204148, 3705.3886108792285, 655.9180102487189, 656.1994366872408, 664.1598378051768, 666.7599664120912, 669.9397054265139, 511.5089514066538, 524.0566979436998, 565.3385538919392, 576.9624974376652, 585.2158108135035, 6384.32964538726, 7677.692892284246, 7801.063872335356, 7973.441859069925, 8040.035195776544, 508.36962166912764, 578.6383510306059, 584.4681339980682, 613.8618810767631, 618.3816183816184, 3277.344531093791, 3774.226283611853, 3783.6027217142355, 3861.4980052593546, 3898.142408670088, 87975.0, 88873.66689499663, 89005.55497222602, 89614.0, 90035.0, 75200.0, 75377.49245015069, 75630.0, 75898.48203035908, 76083.0, 67671.64656706937, 67729.66135169292, 67762.0, 67862.66068669625, 67916.320836791, 7028.789136325919, 7065.293470652894, 7489.474629367307, 7537.2085930976455, 7624.69501219945, 32621.0, 43643.127137457705, 44073.11853762843, 44079.11841763082, 44096.779516102215, 56748.0, 59136.81726365535, 61466.234019468335, 61864.38135618586, 61988.0, 7820.139784623573, 8352.164783521599, 8357.749267521984, 9568.856467152858, 9833.63992641775, 9615.759606009906, 9818.969008254317, 10585.27550242157, 10623.097036751653, 10733.838992414963, 10002.699919002442, 10132.986701330103, 10185.9490702546, 10489.370637761758, 10514.369137851754, 10139.64511242103, 10180.236482264008, 10234.590616375262, 10515.57937682484, 10621.0, 9942.254330925065, 9971.0, 10013.649522266976, 10186.083252507013, 10259.640912568024, 8323.167683231628, 8568.614412351584, 8671.826563468821, 8707.695230666895, 9177.128172823572, 8685.174908383693, 8709.08554601783, 8779.868301975348, 9052.592633331647, 9285.48929808867], "value": 6633.414049529437, "unit": "score"}], "name": "dromaeo_css", "value": 7202.804400973284, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 107.0, 113.0, 115.0, 132.0, 131.0, 96.0, 169.0, 171.0, 106.0], "value": 125.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 156.0, 154.0, 204.0, 160.0, 146.0, 124.0, 161.0, 162.0, 136.0], "value": 157.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 217.0, 270.0, 286.0, 193.0, 283.0, 274.0, 197.0, 197.0, 195.0], "value": 231.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 113.0, 72.0, 155.0, 62.0, 55.0, 57.0, 62.0, 133.0, 46.0], "value": 84.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 124.0, 145.0, 62.0, 96.0, 121.0, 165.0, 120.0, 84.0, 119.0], "value": 112.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 87.0, 88.0, 87.0, 89.0, 87.0, 89.0, 87.0, 89.0, 86.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 106.0, 151.0, 152.0, 114.0, 95.0, 97.0, 96.0, 96.0, 95.0], "value": 112.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 91.0, 90.0, 91.0, 91.0, 90.0, 130.0, 150.0, 91.0, 89.0], "value": 102.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 62.0, 61.0, 51.0, 51.0, 51.0, 77.0, 66.0, 83.0], "value": 62.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 75.0, 70.0, 71.0, 69.0, 66.0, 66.0, 65.0], "value": 67.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 84.0, 85.0, 84.0, 82.0, 85.0, 88.0, 99.0, 108.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 154.0, 166.0, 149.0, 163.0, 159.0, 164.0, 162.0, 253.0, 182.0], "value": 171.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 189.0, 170.0, 172.0, 161.0, 232.0, 189.0, 166.0, 182.0, 195.0], "value": 183.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 64.0, 64.0, 84.0, 82.0, 100.0, 99.0, 69.0, 70.0, 71.0], "value": 77.5, "unit": "score"}], "name": "kraken", "value": 1664.4999999999998, "alertThreshold": 2.0}]} 10:04:59 INFO - Return code: 0 10:04:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:04:59 ERROR - # TBPL SUCCESS # 10:04:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:04:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:04:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:04:59 INFO - 2016-04-23 10:04:59.772 screenresolution[1954:16794] starting screenresolution argv=screenresolution get 10:04:59 INFO - 2016-04-23 10:04:59.774 screenresolution[1954:16794] Display 0: 1600x1200x32@60 10:04:59 INFO - 2016-04-23 10:04:59.780 screenresolution[1955:16797] starting screenresolution argv=screenresolution list 10:04:59 INFO - Available Modes on Display 0 10:04:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:04:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:04:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:04:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:05:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:05:00 INFO - Intel Iris: 10:05:00 INFO - Chipset Model: Intel Iris 10:05:00 INFO - Type: GPU 10:05:00 INFO - Bus: Built-In 10:05:00 INFO - VRAM (Dynamic, Max): 1536 MB 10:05:00 INFO - Vendor: Intel (0x8086) 10:05:00 INFO - Device ID: 0x0a2e 10:05:00 INFO - Revision ID: 0x0009 10:05:00 INFO - Displays: 10:05:00 INFO - Display: 10:05:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:05:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:05:00 INFO - Display Serial Number: 1600x1200 60 10:05:00 INFO - Main Display: Yes 10:05:00 INFO - Mirror: Off 10:05:00 INFO - Online: Yes 10:05:00 INFO - Rotation: Supported 10:05:00 INFO - Return code: 0 10:05:00 INFO - Running post-action listener: _resource_record_post_action 10:05:00 INFO - Running post-run listener: _resource_record_post_run 10:05:01 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 544768; Write bytes: 698464768; Read time: 7; Write time: 2062 10:05:01 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 377965056; Write bytes: 372147712; Read time: 28136; Write time: 687 10:05:01 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 544768; Write bytes: 301069312; Read time: 7; Write time: 1325 10:05:01 INFO - Running post-run listener: _upload_blobber_files 10:05:01 INFO - Blob upload gear active. 10:05:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:05:01 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 10:05:01 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:05:01 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:05:01 INFO - (blobuploader) - INFO - Open directory for files ... 10:05:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:05:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:05:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:05:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:05:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:05:02 INFO - (blobuploader) - INFO - Done attempting. 10:05:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:05:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:05:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:05:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:05:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:05:03 INFO - (blobuploader) - INFO - Done attempting. 10:05:03 INFO - (blobuploader) - INFO - Iteration through files over. 10:05:03 INFO - Return code: 0 10:05:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:05:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:05:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4e97fad123dc209ffd407d8b2c0de89fc1cb5f10f71f5b9c581dff8497b3be97b3531872e056abeac1b47832b028217fcfd437fbe34a5164e93bda1ca9ebd1f6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5ada58384b2cff908a6554addf70b31d76dff73ccb84f26758d0f32a53f18a0fe1a20a86269b91c2236b3e324ebadb42d3b21e896e7da621e9ca5170c0d82211"} 10:05:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:05:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:05:03 INFO - Contents: 10:05:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4e97fad123dc209ffd407d8b2c0de89fc1cb5f10f71f5b9c581dff8497b3be97b3531872e056abeac1b47832b028217fcfd437fbe34a5164e93bda1ca9ebd1f6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5ada58384b2cff908a6554addf70b31d76dff73ccb84f26758d0f32a53f18a0fe1a20a86269b91c2236b3e324ebadb42d3b21e896e7da621e9ca5170c0d82211"} 10:05:03 INFO - Running post-run listener: copy_logs_to_upload_dir 10:05:03 INFO - Copying logs to upload dir... 10:05:03 INFO - mkdir: /builds/slave/test/build/upload/logs 10:05:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.643827 ========= master_lag: 93.64 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 50 secs) (at 2016-04-23 10:06:37.325524) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-23 10:06:37.329285) ========= 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.uFI24kKKnB/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.73h3SlkTNf/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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/Fx-Team/sha512/4e97fad123dc209ffd407d8b2c0de89fc1cb5f10f71f5b9c581dff8497b3be97b3531872e056abeac1b47832b028217fcfd437fbe34a5164e93bda1ca9ebd1f6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5ada58384b2cff908a6554addf70b31d76dff73ccb84f26758d0f32a53f18a0fe1a20a86269b91c2236b3e324ebadb42d3b21e896e7da621e9ca5170c0d82211"} build_url:https://queue.taskcluster.net/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011675 build_url: 'https://queue.taskcluster.net/v1/task/DSHxp28_TVC43EdzwYltKA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4e97fad123dc209ffd407d8b2c0de89fc1cb5f10f71f5b9c581dff8497b3be97b3531872e056abeac1b47832b028217fcfd437fbe34a5164e93bda1ca9ebd1f6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5ada58384b2cff908a6554addf70b31d76dff73ccb84f26758d0f32a53f18a0fe1a20a86269b91c2236b3e324ebadb42d3b21e896e7da621e9ca5170c0d82211"}' ========= master_lag: 0.57 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-23 10:06:37.906811) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-04-23 10:06:37.907130) ========= 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.uFI24kKKnB/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.73h3SlkTNf/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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.005110 ========= master_lag: 2.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-04-23 10:06:39.924340) ========= ========= Started reboot skipped (results: 3, elapsed: 3 secs) (at 2016-04-23 10:06:39.924618) ========= ========= Finished reboot skipped (results: 3, elapsed: 3 secs) (at 2016-04-23 10:06:43.494227) ========= ========= Total master_lag: 96.29 =========