builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0033 starttime: 1461962846.66 results: success (0) buildid: 20160429124142 builduid: 61bfefa969d1459e81e8017f529649f7 revision: d2674a65a6e8a0bd7c0954cbdf2a2babc8921a57 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 13:47:26.656374) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-29 13:47:26.656848) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-29 13:47:26.657131) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-29 13:47:26.680083) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 13:47:26.680454) ========= 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.x3hjBBDkgU/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.A8aWbgIiAW/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-29 13:47:26-- 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% 150M=0s 2016-04-29 13:47:26 (150 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.074497 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 13:47:26.771544) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 13:47:26.774882) ========= 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.x3hjBBDkgU/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.A8aWbgIiAW/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.020202 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-29 13:47:26.823706) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-04-29 13:47:26.824067) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d2674a65a6e8a0bd7c0954cbdf2a2babc8921a57 --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 d2674a65a6e8a0bd7c0954cbdf2a2babc8921a57 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.x3hjBBDkgU/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.A8aWbgIiAW/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-29 13:47:26,901 truncating revision to first 12 chars 2016-04-29 13:47:26,901 Setting DEBUG logging. 2016-04-29 13:47:26,901 attempt 1/10 2016-04-29 13:47:26,901 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d2674a65a6e8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-29 13:47:27,937 unpacking tar archive at: fx-team-d2674a65a6e8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.303095 ========= master_lag: 2.55 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-04-29 13:47:30.673373) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 13:47:30.673778) ========= script_repo_revision: d2674a65a6e8a0bd7c0954cbdf2a2babc8921a57 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-29 13:47:30.674198) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-29 13:47:30.674481) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-29 13:47:30.712739) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-04-29 13:47:30.713307) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.x3hjBBDkgU/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.A8aWbgIiAW/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 13:47:30 INFO - MultiFileLogger online at 20160429 13:47:30 in /builds/slave/test 13:47:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 13:47:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:47:30 INFO - {'append_to_log': False, 13:47:30 INFO - 'base_work_dir': '/builds/slave/test', 13:47:30 INFO - 'blob_upload_branch': 'Fx-Team', 13:47:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:47:30 INFO - 'branch': 'Fx-Team', 13:47:30 INFO - 'buildbot_json_path': 'buildprops.json', 13:47:30 INFO - 'config_files': ('talos/mac_config.py',), 13:47:30 INFO - 'default_actions': ('clobber', 13:47:30 INFO - 'read-buildbot-config', 13:47:30 INFO - 'download-and-extract', 13:47:30 INFO - 'populate-webroot', 13:47:30 INFO - 'create-virtualenv', 13:47:30 INFO - 'install', 13:47:30 INFO - 'run-tests'), 13:47:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:47:30 INFO - 'download_minidump_stackwalk': True, 13:47:30 INFO - 'download_symbols': 'ondemand', 13:47:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:47:30 INFO - 'tooltool.py': '/tools/tooltool.py', 13:47:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:47:30 INFO - '/tools/misc-python/virtualenv.py')}, 13:47:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:47:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:47:30 INFO - 'installer_path': 'installer.exe', 13:47:30 INFO - 'log_level': 'info', 13:47:30 INFO - 'log_name': 'talos', 13:47:30 INFO - 'log_to_console': True, 13:47:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:47:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:47:30 INFO - 'opt_config_files': (), 13:47:30 INFO - 'pip_index': False, 13:47:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:47:30 INFO - 'cmd': ('bash', 13:47:30 INFO - '-c', 13:47:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:47:30 INFO - 'enabled': True, 13:47:30 INFO - 'halt_on_failure': False, 13:47:30 INFO - 'name': 'check_screen_resolution'},), 13:47:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:47:30 INFO - 'cmd': ('bash', 13:47:30 INFO - '-c', 13:47:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:47:30 INFO - 'enabled': True, 13:47:30 INFO - 'halt_on_failure': False, 13:47:30 INFO - 'name': 'check_screen_resolution'},), 13:47:30 INFO - 'run_cmd_checks_enabled': True, 13:47:30 INFO - 'sps_profile': False, 13:47:30 INFO - 'sps_profile_interval': 0, 13:47:30 INFO - 'suite': 'dromaeojs', 13:47:30 INFO - 'system_bits': '32', 13:47:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:47:30 INFO - 'title': 't-yosemite-r7-0033', 13:47:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:47:30 INFO - 'use_talos_json': True, 13:47:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:47:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:47:30 INFO - 'work_dir': 'build'} 13:47:30 INFO - ##### 13:47:30 INFO - ##### Running clobber step. 13:47:30 INFO - ##### 13:47:30 INFO - Running pre-action listener: _resource_record_pre_action 13:47:30 INFO - Running main action method: clobber 13:47:30 INFO - rmtree: /builds/slave/test/build 13:47:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:47:33 INFO - Running post-action listener: _resource_record_post_action 13:47:33 INFO - ##### 13:47:33 INFO - ##### Running read-buildbot-config step. 13:47:33 INFO - ##### 13:47:33 INFO - Running pre-action listener: _resource_record_pre_action 13:47:33 INFO - Running main action method: read_buildbot_config 13:47:33 INFO - Using buildbot properties: 13:47:33 INFO - { 13:47:33 INFO - "project": "", 13:47:33 INFO - "product": "firefox", 13:47:33 INFO - "script_repo_revision": "production", 13:47:33 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 13:47:33 INFO - "repository": "", 13:47:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 13:47:33 INFO - "buildid": "20160429124142", 13:47:33 INFO - "pgo_build": "False", 13:47:33 INFO - "basedir": "/builds/slave/test", 13:47:33 INFO - "buildnumber": 679, 13:47:33 INFO - "slavename": "t-yosemite-r7-0033", 13:47:33 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 13:47:33 INFO - "platform": "yosemite_r7", 13:47:33 INFO - "scheduler": "tests-fx-team-macosx64-talos", 13:47:33 INFO - "branch": "fx-team", 13:47:33 INFO - "repo_path": "integration/fx-team", 13:47:33 INFO - "revision": "d2674a65a6e8a0bd7c0954cbdf2a2babc8921a57", 13:47:33 INFO - "stage_platform": "macosx64", 13:47:33 INFO - "builduid": "61bfefa969d1459e81e8017f529649f7", 13:47:33 INFO - "slavebuilddir": "test" 13:47:33 INFO - } 13:47:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 13:47:33 INFO - Running post-action listener: _resource_record_post_action 13:47:33 INFO - ##### 13:47:33 INFO - ##### Running download-and-extract step. 13:47:33 INFO - ##### 13:47:33 INFO - Running pre-action listener: _resource_record_pre_action 13:47:33 INFO - Running main action method: download_and_extract 13:47:33 INFO - mkdir: /builds/slave/test/build/tests 13:47:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:47:33 INFO - https://queue.taskcluster.net/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:47:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:47:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:47:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 13:47:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 13:47:34 INFO - Downloaded 1199 bytes. 13:47:34 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 13:47:34 INFO - Using the following test package requirements: 13:47:34 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:47:34 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:47:34 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 13:47:34 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:47:34 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 13:47:34 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:47:34 INFO - u'jsshell-mac.zip'], 13:47:34 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:47:34 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 13:47:34 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:47:34 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:47:34 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 13:47:34 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:47:34 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 13:47:34 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:47:34 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 13:47:34 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:47:34 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 13:47:34 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:47:34 INFO - https://queue.taskcluster.net/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:47:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:47:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:47:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:47:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:47:36 INFO - Downloaded 18124289 bytes. 13:47:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:47:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:47:39 INFO - Return code: 0 13:47:39 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 13:47:39 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')]} 13:47:39 INFO - https://queue.taskcluster.net/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:47:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:47:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:47:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:47:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:47:40 INFO - Downloaded 18124289 bytes. 13:47:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:47:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:47:44 INFO - Return code: 0 13:47:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:47:44 INFO - https://queue.taskcluster.net/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:47:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:47:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:47:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:47:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:47:45 INFO - Downloaded 10840145 bytes. 13:47:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:47:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 13:47:46 INFO - Return code: 0 13:47: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')]} 13:47:46 INFO - https://queue.taskcluster.net/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 13:47:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:47:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:47:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 13:47:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 13:47:51 INFO - Downloaded 110275772 bytes. 13:47:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:47:51 INFO - mkdir: /builds/slave/test/properties 13:47:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:47:51 INFO - Writing to file /builds/slave/test/properties/build_url 13:47:51 INFO - Contents: 13:47:51 INFO - build_url:https://queue.taskcluster.net/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:47:52 INFO - Running post-action listener: _resource_record_post_action 13:47:52 INFO - Running post-action listener: set_extra_try_arguments 13:47:52 INFO - ##### 13:47:52 INFO - ##### Running populate-webroot step. 13:47:52 INFO - ##### 13:47:52 INFO - Running pre-action listener: _resource_record_pre_action 13:47:52 INFO - Running main action method: populate_webroot 13:47:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:47:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:47:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:47:52 INFO - '--tppagecycles', 13:47:52 INFO - '7'], 13:47:52 INFO - 'tests': ['tsvgm']}}, 13:47:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:47:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:47:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:47:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:47:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:47:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:47:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:47:52 INFO - 'talos_options': ['--disable-e10s'], 13:47:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:47:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:47:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:47:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:47:52 INFO - 'talos_options': ['--disable-e10s'], 13:47:52 INFO - 'tests': ['damp', 'tps']}, 13:47:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:47:52 INFO - 'tests': ['damp', 'tps']}, 13:47:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:47:52 INFO - 'tests': ['dromaeo_dom']}, 13:47:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:47:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:47:52 INFO - 'tests': ['a11yr', 13:47:52 INFO - 'ts_paint', 13:47:52 INFO - 'tpaint', 13:47:52 INFO - 'sessionrestore', 13:47:52 INFO - 'sessionrestore_no_auto_restore', 13:47:52 INFO - 'tabpaint']}, 13:47:52 INFO - 'other-e10s': {'tests': ['a11yr', 13:47:52 INFO - 'ts_paint', 13:47:52 INFO - 'tpaint', 13:47:52 INFO - 'sessionrestore', 13:47:52 INFO - 'sessionrestore_no_auto_restore', 13:47:52 INFO - 'tabpaint']}, 13:47:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:47:52 INFO - 'ts_paint', 13:47:52 INFO - 'tpaint', 13:47:52 INFO - 'sessionrestore', 13:47:52 INFO - 'sessionrestore_no_auto_restore', 13:47:52 INFO - 'tabpaint']}, 13:47:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:47:52 INFO - 'ts_paint', 13:47:52 INFO - 'tpaint', 13:47:52 INFO - 'sessionrestore', 13:47:52 INFO - 'sessionrestore_no_auto_restore', 13:47:52 INFO - 'tabpaint']}, 13:47:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:47:52 INFO - 'tests': ['a11yr', 13:47:52 INFO - 'ts_paint', 13:47:52 INFO - 'tpaint', 13:47:52 INFO - 'sessionrestore', 13:47:52 INFO - 'sessionrestore_no_auto_restore', 13:47:52 INFO - 'tabpaint']}, 13:47:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:47:52 INFO - 'tests': ['a11yr', 13:47:52 INFO - 'ts_paint', 13:47:52 INFO - 'tpaint', 13:47:52 INFO - 'sessionrestore', 13:47:52 INFO - 'sessionrestore_no_auto_restore', 13:47:52 INFO - 'tabpaint']}, 13:47:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:47:52 INFO - 'tests': ['tsvgx', 13:47:52 INFO - 'tsvgr_opacity', 13:47:52 INFO - 'tart', 13:47:52 INFO - 'tscrollx', 13:47:52 INFO - 'cart']}, 13:47:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:47:52 INFO - 'tsvgr_opacity', 13:47:52 INFO - 'tart', 13:47:52 INFO - 'tscrollx', 13:47:52 INFO - 'cart']}, 13:47:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:47:52 INFO - 'talos_options': ['--disable-e10s'], 13:47:52 INFO - 'tests': ['tp5o']}, 13:47:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:47:52 INFO - 'tests': ['tp5o']}, 13:47:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:47:52 INFO - 'talos_options': ['--disable-e10s', 13:47:52 INFO - '--xperf_path', 13:47:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:47:52 INFO - 'tests': ['tp5n']}, 13:47:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:47:52 INFO - 'talos_options': ['--xperf_path', 13:47:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:47:52 INFO - 'tests': ['tp5n']}}, 13:47:52 INFO - 'talos.zip': {'path': '', 13:47:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:47:52 INFO - Running post-action listener: _resource_record_post_action 13:47:52 INFO - ##### 13:47:52 INFO - ##### Running create-virtualenv step. 13:47:52 INFO - ##### 13:47:52 INFO - Running pre-action listener: _resource_record_pre_action 13:47:52 INFO - Running main action method: create_virtualenv 13:47:52 INFO - Creating virtualenv /builds/slave/test/build/venv 13:47:52 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 13:47:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:47:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:47:52 INFO - Using real prefix '/tools/python27' 13:47:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:47:52 INFO - Installing distribute.............................................................................................................................................................................................done. 13:47:55 INFO - Installing pip.................done. 13:47:55 INFO - Return code: 0 13:47:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:55 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 0x10de63a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de73420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b8591190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd02e00>, '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 0x10db2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcfdb20>, '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.x3hjBBDkgU/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.A8aWbgIiAW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 13:47:55 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 13:47:55 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 13:47:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x3hjBBDkgU/Render', 13:47:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:55 INFO - 'HOME': '/Users/cltbld', 13:47:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:55 INFO - 'LOGNAME': 'cltbld', 13:47:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:55 INFO - 'MOZ_NO_REMOTE': '1', 13:47:55 INFO - 'NO_EM_RESTART': '1', 13:47:55 INFO - 'PAGER': '/bin/cat', 13:47:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:55 INFO - 'PWD': '/builds/slave/test', 13:47:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:55 INFO - 'SHELL': '/bin/bash', 13:47:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A8aWbgIiAW/Listeners', 13:47:55 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 13:47:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:55 INFO - 'USER': 'cltbld', 13:47:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:55 INFO - 'XPC_FLAGS': '0x0', 13:47:55 INFO - 'XPC_SERVICE_NAME': '0', 13:47:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:56 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 13:47:56 INFO - Cleaning up... 13:47:56 INFO - Return code: 0 13:47:56 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10de63a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de73420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b8591190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd02e00>, '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 0x10db2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcfdb20>, '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.x3hjBBDkgU/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.A8aWbgIiAW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 13:47:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 13:47:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 13:47:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x3hjBBDkgU/Render', 13:47:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:56 INFO - 'HOME': '/Users/cltbld', 13:47:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:56 INFO - 'LOGNAME': 'cltbld', 13:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:56 INFO - 'MOZ_NO_REMOTE': '1', 13:47:56 INFO - 'NO_EM_RESTART': '1', 13:47:56 INFO - 'PAGER': '/bin/cat', 13:47:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:56 INFO - 'PWD': '/builds/slave/test', 13:47:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:56 INFO - 'SHELL': '/bin/bash', 13:47:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A8aWbgIiAW/Listeners', 13:47:56 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 13:47:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:56 INFO - 'USER': 'cltbld', 13:47:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:56 INFO - 'XPC_FLAGS': '0x0', 13:47:56 INFO - 'XPC_SERVICE_NAME': '0', 13:47:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:56 INFO - Downloading/unpacking psutil>=0.7.1 13:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:47:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:47:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:47:58 INFO - Installing collected packages: psutil 13:47:58 INFO - Running setup.py install for psutil 13:47:59 INFO - building 'psutil._psutil_osx' extension 13:47:59 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 13:47:59 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 13:47:59 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 13:47:59 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 13:47:59 INFO - building 'psutil._psutil_posix' extension 13:47:59 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 13:47:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:47:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:47:59 INFO - ^ 13:47:59 INFO - 1 warning generated. 13:47:59 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 13:47:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:47:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:47:59 INFO - Successfully installed psutil 13:47:59 INFO - Cleaning up... 13:47:59 INFO - Return code: 0 13:47:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:47:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:47:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:47:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10de63a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de73420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b8591190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd02e00>, '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 0x10db2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcfdb20>, '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.x3hjBBDkgU/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.A8aWbgIiAW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 13:47:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 13:47:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 13:47:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x3hjBBDkgU/Render', 13:47:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:59 INFO - 'HOME': '/Users/cltbld', 13:47:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:59 INFO - 'LOGNAME': 'cltbld', 13:47:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:59 INFO - 'MOZ_NO_REMOTE': '1', 13:47:59 INFO - 'NO_EM_RESTART': '1', 13:47:59 INFO - 'PAGER': '/bin/cat', 13:47:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:59 INFO - 'PWD': '/builds/slave/test', 13:47:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:59 INFO - 'SHELL': '/bin/bash', 13:47:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A8aWbgIiAW/Listeners', 13:47:59 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 13:47:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:59 INFO - 'USER': 'cltbld', 13:47:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:59 INFO - 'XPC_FLAGS': '0x0', 13:47:59 INFO - 'XPC_SERVICE_NAME': '0', 13:47:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:00 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:48:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:02 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:48:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:48:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:48:02 INFO - Installing collected packages: mozsystemmonitor 13:48:02 INFO - Running setup.py install for mozsystemmonitor 13:48:02 INFO - Successfully installed mozsystemmonitor 13:48:02 INFO - Cleaning up... 13:48:02 INFO - Return code: 0 13:48:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:48:02 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')]} 13:48:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:02 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')]} 13:48:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:02 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 0x10de63a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de73420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b8591190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd02e00>, '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 0x10db2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcfdb20>, '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.x3hjBBDkgU/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.A8aWbgIiAW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 13:48:02 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 13:48:02 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 13:48:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x3hjBBDkgU/Render', 13:48:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:02 INFO - 'HOME': '/Users/cltbld', 13:48:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:02 INFO - 'LOGNAME': 'cltbld', 13:48:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:02 INFO - 'MOZ_NO_REMOTE': '1', 13:48:02 INFO - 'NO_EM_RESTART': '1', 13:48:02 INFO - 'PAGER': '/bin/cat', 13:48:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:02 INFO - 'PWD': '/builds/slave/test', 13:48:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:02 INFO - 'SHELL': '/bin/bash', 13:48:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A8aWbgIiAW/Listeners', 13:48:02 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 13:48:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:02 INFO - 'USER': 'cltbld', 13:48:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:02 INFO - 'XPC_FLAGS': '0x0', 13:48:02 INFO - 'XPC_SERVICE_NAME': '0', 13:48:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:03 INFO - Downloading/unpacking blobuploader==1.2.4 13:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:05 INFO - Downloading blobuploader-1.2.4.tar.gz 13:48:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:48:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:48:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:48:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:48:06 INFO - Downloading docopt-0.6.1.tar.gz 13:48:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:48:06 INFO - Installing collected packages: blobuploader, requests, docopt 13:48:06 INFO - Running setup.py install for blobuploader 13:48:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:48:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:48:06 INFO - Running setup.py install for requests 13:48:07 INFO - Running setup.py install for docopt 13:48:07 INFO - Successfully installed blobuploader requests docopt 13:48:07 INFO - Cleaning up... 13:48:07 INFO - Return code: 0 13:48:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:48:07 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')]} 13:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:07 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')]} 13:48:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:07 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 0x10de63a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de73420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b8591190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd02e00>, '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 0x10db2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcfdb20>, '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.x3hjBBDkgU/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.A8aWbgIiAW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 13:48:07 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 13:48:07 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 13:48:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x3hjBBDkgU/Render', 13:48:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:07 INFO - 'HOME': '/Users/cltbld', 13:48:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:07 INFO - 'LOGNAME': 'cltbld', 13:48:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:07 INFO - 'MOZ_NO_REMOTE': '1', 13:48:07 INFO - 'NO_EM_RESTART': '1', 13:48:07 INFO - 'PAGER': '/bin/cat', 13:48:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:07 INFO - 'PWD': '/builds/slave/test', 13:48:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:07 INFO - 'SHELL': '/bin/bash', 13:48:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A8aWbgIiAW/Listeners', 13:48:07 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 13:48:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:07 INFO - 'USER': 'cltbld', 13:48:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:07 INFO - 'XPC_FLAGS': '0x0', 13:48:07 INFO - 'XPC_SERVICE_NAME': '0', 13:48:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:48:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-_KX1Y2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:48:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:48:07 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-iErQu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:48:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-mFx4cy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:48:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-JywsR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:48:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-tt_k9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:48:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-6txtqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:48:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-jPUQ8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:48:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-jvzArE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:48:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-viw0iG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:48:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-zhj4vt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:48:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-OybrIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:48:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:48:08 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-MJa3Ru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:48:09 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-wGQwLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:48:09 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-8uEVAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:48:09 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-vSdcnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:48:09 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-A5jaCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:48:09 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-3pYPmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:48:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:48:09 INFO - Running setup.py install for manifestparser 13:48:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:48:09 INFO - Running setup.py install for mozcrash 13:48:09 INFO - Running setup.py install for mozdebug 13:48:10 INFO - Running setup.py install for mozdevice 13:48:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:48:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:48:10 INFO - Running setup.py install for mozfile 13:48:10 INFO - Running setup.py install for mozhttpd 13:48:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:48:10 INFO - Running setup.py install for mozinfo 13:48:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:48:10 INFO - Running setup.py install for mozInstall 13:48:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:48:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:48:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:48:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:48:10 INFO - Running setup.py install for mozleak 13:48:10 INFO - Running setup.py install for mozlog 13:48:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:48:11 INFO - Running setup.py install for moznetwork 13:48:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:48:11 INFO - Running setup.py install for mozprocess 13:48:11 INFO - Running setup.py install for mozprofile 13:48:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:48:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:48:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:48:11 INFO - Running setup.py install for mozrunner 13:48:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:48:11 INFO - Running setup.py install for mozscreenshot 13:48:11 INFO - Running setup.py install for moztest 13:48:12 INFO - Running setup.py install for mozversion 13:48:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:48:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:48:12 INFO - Cleaning up... 13:48:12 INFO - Return code: 0 13:48:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:48:12 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')]} 13:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:12 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')]} 13:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:12 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 0x10de63a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de73420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b8591190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd02e00>, '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 0x10db2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcfdb20>, '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.x3hjBBDkgU/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.A8aWbgIiAW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 13:48:12 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 13:48:12 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 13:48:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x3hjBBDkgU/Render', 13:48:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:12 INFO - 'HOME': '/Users/cltbld', 13:48:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:12 INFO - 'LOGNAME': 'cltbld', 13:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:12 INFO - 'MOZ_NO_REMOTE': '1', 13:48:12 INFO - 'NO_EM_RESTART': '1', 13:48:12 INFO - 'PAGER': '/bin/cat', 13:48:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:12 INFO - 'PWD': '/builds/slave/test', 13:48:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:12 INFO - 'SHELL': '/bin/bash', 13:48:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A8aWbgIiAW/Listeners', 13:48:12 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 13:48:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:12 INFO - 'USER': 'cltbld', 13:48:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:12 INFO - 'XPC_FLAGS': '0x0', 13:48:12 INFO - 'XPC_SERVICE_NAME': '0', 13:48:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:48:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-tHndL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:48:12 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)) 13:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:48:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-nn5pOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:48:12 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)) 13:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:48:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-BWvb62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:48:12 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)) 13:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:48:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-QZxeHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:48:12 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)) 13:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:48:12 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-UtZEMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:48:12 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)) 13:48:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:48:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-fCMRaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:48:13 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)) 13:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:48:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-SM4VZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:48:13 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)) 13:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:48:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-VCyFyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:48:13 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)) 13:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:48:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-_4U08L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:48:13 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)) 13:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:48:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-hl2huM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:48:13 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)) 13:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:48:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-5HyuqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:48:13 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)) 13:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:48:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-BB5WzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:48:13 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)) 13:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:48:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-sA7qgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:48:13 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)) 13:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:48:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-hE3FnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:48:13 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)) 13:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:48:13 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-Bbe3wq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:48:14 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)) 13:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:48:14 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-qnt5jG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:48:14 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)) 13:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:48:14 INFO - Running setup.py (path:/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/pip-833Mha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:48:14 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)) 13:48:14 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)) 13:48:14 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)) 13:48:14 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)) 13:48:14 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)) 13:48:14 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)) 13:48:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:48:14 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 13:48:14 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 13:48:14 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 13:48:14 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 13:48:14 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 13:48:14 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 13:48:16 INFO - Downloading blessings-1.6.tar.gz 13:48:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:48:16 INFO - Installing collected packages: blessings 13:48:16 INFO - Running setup.py install for blessings 13:48:16 INFO - Successfully installed blessings 13:48:16 INFO - Cleaning up... 13:48:17 INFO - Return code: 0 13:48:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:48:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:48:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:48:17 INFO - Reading from file tmpfile_stdout 13:48:17 INFO - Current package versions: 13:48:17 INFO - blessings == 1.6 13:48:17 INFO - blobuploader == 1.2.4 13:48:17 INFO - docopt == 0.6.1 13:48:17 INFO - manifestparser == 1.1 13:48:17 INFO - mozInstall == 1.12 13:48:17 INFO - mozcrash == 0.17 13:48:17 INFO - mozdebug == 0.1 13:48:17 INFO - mozdevice == 0.48 13:48:17 INFO - mozfile == 1.2 13:48:17 INFO - mozhttpd == 0.7 13:48:17 INFO - mozinfo == 0.9 13:48:17 INFO - mozleak == 0.1 13:48:17 INFO - mozlog == 3.1 13:48:17 INFO - moznetwork == 0.27 13:48:17 INFO - mozprocess == 0.22 13:48:17 INFO - mozprofile == 0.28 13:48:17 INFO - mozrunner == 6.11 13:48:17 INFO - mozscreenshot == 0.1 13:48:17 INFO - mozsystemmonitor == 0.0 13:48:17 INFO - moztest == 0.7 13:48:17 INFO - mozversion == 1.4 13:48:17 INFO - psutil == 3.1.1 13:48:17 INFO - requests == 1.2.3 13:48:17 INFO - wsgiref == 0.1.2 13:48:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:48:17 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')]} 13:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:17 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')]} 13:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:17 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 0x10de63a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de73420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b8591190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd02e00>, '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 0x10db2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcfdb20>, '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.x3hjBBDkgU/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.A8aWbgIiAW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 13:48:17 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 13:48:17 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 13:48:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x3hjBBDkgU/Render', 13:48:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:17 INFO - 'HOME': '/Users/cltbld', 13:48:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:17 INFO - 'LOGNAME': 'cltbld', 13:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:17 INFO - 'MOZ_NO_REMOTE': '1', 13:48:17 INFO - 'NO_EM_RESTART': '1', 13:48:17 INFO - 'PAGER': '/bin/cat', 13:48:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:17 INFO - 'PWD': '/builds/slave/test', 13:48:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:17 INFO - 'SHELL': '/bin/bash', 13:48:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A8aWbgIiAW/Listeners', 13:48:17 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 13:48:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:17 INFO - 'USER': 'cltbld', 13:48:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:17 INFO - 'XPC_FLAGS': '0x0', 13:48:17 INFO - 'XPC_SERVICE_NAME': '0', 13:48:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:17 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)) 13:48:17 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)) 13:48:17 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)) 13:48:17 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)) 13:48:17 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)) 13:48:17 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)) 13:48:17 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)) 13:48:17 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)) 13:48:17 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)) 13:48:17 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)) 13:48:17 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)) 13:48:17 INFO - Cleaning up... 13:48:17 INFO - Return code: 0 13:48:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:48:17 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')]} 13:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:17 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')]} 13:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:17 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 0x10de63a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de73420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b8591190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd02e00>, '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 0x10db2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcfdb20>, '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.x3hjBBDkgU/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.A8aWbgIiAW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 13:48:17 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 13:48:17 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 13:48:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x3hjBBDkgU/Render', 13:48:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:17 INFO - 'HOME': '/Users/cltbld', 13:48:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:17 INFO - 'LOGNAME': 'cltbld', 13:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:17 INFO - 'MOZ_NO_REMOTE': '1', 13:48:17 INFO - 'NO_EM_RESTART': '1', 13:48:17 INFO - 'PAGER': '/bin/cat', 13:48:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:17 INFO - 'PWD': '/builds/slave/test', 13:48:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:17 INFO - 'SHELL': '/bin/bash', 13:48:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A8aWbgIiAW/Listeners', 13:48:17 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 13:48:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:17 INFO - 'USER': 'cltbld', 13:48:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:17 INFO - 'XPC_FLAGS': '0x0', 13:48:17 INFO - 'XPC_SERVICE_NAME': '0', 13:48:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:17 INFO - Downloading/unpacking jsonschema 13:48:17 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 13:48:17 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 13:48:17 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 13:48:17 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 13:48:17 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 13:48:17 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 13:48:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:48:20 INFO - Downloading/unpacking functools32 (from jsonschema) 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48:20 INFO - Downloading functools32-3.2.3-2.tar.gz 13:48:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:48:20 INFO - warning: no files found matching '*.txt' 13:48:20 INFO - no previously-included directories found matching 'build' 13:48:20 INFO - no previously-included directories found matching 'dist' 13:48:20 INFO - no previously-included directories found matching '.git*' 13:48:20 INFO - Installing collected packages: jsonschema, functools32 13:48:20 INFO - Running setup.py install for functools32 13:48:20 INFO - warning: no files found matching '*.txt' 13:48:20 INFO - no previously-included directories found matching 'build' 13:48:20 INFO - no previously-included directories found matching 'dist' 13:48:20 INFO - no previously-included directories found matching '.git*' 13:48:21 INFO - Successfully installed jsonschema functools32 13:48:21 INFO - Cleaning up... 13:48:21 INFO - Return code: 0 13:48:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:48:21 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')]} 13:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:48:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:21 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')]} 13:48:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:48:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:48:21 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 0x10de63a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de73420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff2b8591190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd02e00>, '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 0x10db2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcfdb20>, '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.x3hjBBDkgU/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.A8aWbgIiAW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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 13:48:21 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 13:48:21 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 13:48:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x3hjBBDkgU/Render', 13:48:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:48:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:48:21 INFO - 'HOME': '/Users/cltbld', 13:48:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:48:21 INFO - 'LOGNAME': 'cltbld', 13:48:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:48:21 INFO - 'MOZ_NO_REMOTE': '1', 13:48:21 INFO - 'NO_EM_RESTART': '1', 13:48:21 INFO - 'PAGER': '/bin/cat', 13:48:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:48:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:48:21 INFO - 'PWD': '/builds/slave/test', 13:48:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:48:21 INFO - 'SHELL': '/bin/bash', 13:48:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A8aWbgIiAW/Listeners', 13:48:21 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 13:48:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:48:21 INFO - 'USER': 'cltbld', 13:48:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:48:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:48:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:48:21 INFO - 'XPC_FLAGS': '0x0', 13:48:21 INFO - 'XPC_SERVICE_NAME': '0', 13:48:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:48:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:48:21 INFO - Downloading/unpacking flake8 13:48:21 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 13:48:21 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 13:48:21 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 13:48:21 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 13:48:21 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 13:48:21 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 13:48:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:48:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:48:23 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 13:48:23 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 13:48:23 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 13:48:23 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 13:48:23 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 13:48:23 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 13:48:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:48:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48: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 13:48:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:48:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:48:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:48:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:48:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:48:25 INFO - no previously-included directories found matching 'docs/_build' 13:48:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:48:25 INFO - Running setup.py install for pep8 13:48:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:48:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:48:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:48:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:48:25 INFO - no previously-included directories found matching 'docs/_build' 13:48:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:48:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:48:25 INFO - Cleaning up... 13:48:25 INFO - Return code: 0 13:48:25 INFO - Running post-action listener: _resource_record_post_action 13:48:25 INFO - Running post-action listener: _start_resource_monitoring 13:48:25 INFO - Starting resource monitoring. 13:48:25 INFO - ##### 13:48:25 INFO - ##### Running install step. 13:48:25 INFO - ##### 13:48:25 INFO - Running pre-action listener: _resource_record_pre_action 13:48:25 INFO - Running main action method: install 13:48:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:48:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:48:25 INFO - Reading from file tmpfile_stdout 13:48:25 INFO - Detecting whether we're running mozinstall >=1.0... 13:48:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:48:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:48:25 INFO - Reading from file tmpfile_stdout 13:48:25 INFO - Output received: 13:48:25 INFO - Usage: mozinstall [options] installer 13:48:25 INFO - Options: 13:48:25 INFO - -h, --help show this help message and exit 13:48:25 INFO - -d DEST, --destination=DEST 13:48:25 INFO - Directory to install application into. [default: 13:48:25 INFO - "/builds/slave/test"] 13:48:25 INFO - --app=APP Application being installed. [default: firefox] 13:48:25 INFO - mkdir: /builds/slave/test/build/application 13:48:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:48:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:48:59 INFO - Reading from file tmpfile_stdout 13:48:59 INFO - Output received: 13:48:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:48:59 INFO - Running post-action listener: _resource_record_post_action 13:48:59 INFO - ##### 13:48:59 INFO - ##### Running run-tests step. 13:48:59 INFO - ##### 13:48:59 INFO - Running pre-action listener: _resource_record_pre_action 13:48:59 INFO - Running main action method: run_tests 13:48:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:48:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:48:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:48:59 INFO - 2016-04-29 13:48:59.317 screenresolution[1934:18689] starting screenresolution argv=screenresolution get 13:48:59 INFO - 2016-04-29 13:48:59.319 screenresolution[1934:18689] Display 0: 1600x1200x32@60 13:48:59 INFO - 2016-04-29 13:48:59.325 screenresolution[1935:18691] starting screenresolution argv=screenresolution list 13:48:59 INFO - Available Modes on Display 0 13:48:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:48:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:48:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:48:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:48:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:48:59 INFO - Intel Iris: 13:48:59 INFO - Chipset Model: Intel Iris 13:48:59 INFO - Type: GPU 13:48:59 INFO - Bus: Built-In 13:48:59 INFO - VRAM (Dynamic, Max): 1536 MB 13:48:59 INFO - Vendor: Intel (0x8086) 13:48:59 INFO - Device ID: 0x0a2e 13:48:59 INFO - Revision ID: 0x0009 13:48:59 INFO - Displays: 13:48:59 INFO - Display: 13:48:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:48:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:48:59 INFO - Display Serial Number: 1600x1200 60 13:48:59 INFO - Main Display: Yes 13:48:59 INFO - Mirror: Off 13:48:59 INFO - Online: Yes 13:48:59 INFO - Rotation: Supported 13:48:59 INFO - Return code: 0 13:48:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:48:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:48:59 INFO - Python 2.7.3 13:48:59 INFO - Return code: 0 13:48:59 INFO - grabbing minidump binary from tooltool 13:48:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:48:59 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 0x10dd02e00>, '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 0x10db2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dcfdb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:48:59 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 13:48:59 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 13:48:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:48:59 INFO - Return code: 0 13:48:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:48:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:48:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:48:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:48:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:48:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:48:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:49:00 INFO - Return code: 0 13:49:00 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', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:49:00 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 --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0033 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:49:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x3hjBBDkgU/Render', 13:49:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:49:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:49:00 INFO - 'HOME': '/Users/cltbld', 13:49:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:49:00 INFO - 'LOGNAME': 'cltbld', 13:49:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:49:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:49:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:49:00 INFO - 'MOZ_NO_REMOTE': '1', 13:49:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:49:00 INFO - 'NO_EM_RESTART': '1', 13:49:00 INFO - 'PAGER': '/bin/cat', 13:49:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:49:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:49:00 INFO - 'PWD': '/builds/slave/test', 13:49:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:49:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:49:00 INFO - 'SHELL': '/bin/bash', 13:49:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A8aWbgIiAW/Listeners', 13:49:00 INFO - 'TMPDIR': '/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/', 13:49:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:49:00 INFO - 'USER': 'cltbld', 13:49:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:49:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:49:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:49:00 INFO - 'XPC_FLAGS': '0x0', 13:49:00 INFO - 'XPC_SERVICE_NAME': '0', 13:49:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:49:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:49:00 INFO - mozversion application_buildid: 20160429124142 13:49:00 INFO - mozversion application_changeset: d2674a65a6e8a0bd7c0954cbdf2a2babc8921a57 13:49:00 INFO - mozversion application_display_name: Nightly 13:49:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:49:00 INFO - mozversion application_name: Firefox 13:49:00 INFO - mozversion application_remotingname: firefox 13:49:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 13:49:00 INFO - mozversion application_vendor: Mozilla 13:49:00 INFO - mozversion application_version: 49.0a1 13:49:00 INFO - mozversion platform_buildid: 20160429124142 13:49:00 INFO - mozversion platform_changeset: d2674a65a6e8a0bd7c0954cbdf2a2babc8921a57 13:49:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 13:49:00 INFO - mozversion platform_version: 49.0a1 13:49:00 INFO - using testdate: 1461962940 13:49:00 INFO - actual date: 1461962940 13:49:00 INFO - starting webserver on 'localhost:57363' 13:49:00 INFO - SUITE-START | Running 2 tests 13:49:00 INFO - TEST-START | dromaeo_css 13:49:00 INFO - operating with platform_type : mac_ 13:49:00 INFO - Initialising browser for dromaeo_css test... 13:49:00 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmp8CtxOK/profile http://localhost:57363/getInfo.html) 13:49:03 INFO - PROCESS | 1953 | __metrics Screen width/height:1600/1200 13:49:03 INFO - PROCESS | 1953 | colorDepth:24 13:49:03 INFO - PROCESS | 1953 | Browser inner width/height: 1024/689 13:49:03 INFO - PROCESS | 1953 | __metrics 13:49:03 INFO - PROCESS | 1953 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:49:03 ERROR - PROCESS | 1953 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:49:03 INFO - TEST-INFO | 1953: exit 0 13:49:03 INFO - Browser initialized. 13:49:03 INFO - Running cycle 1/1 for dromaeo_css test... 13:49:03 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmp8CtxOK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:49:15 INFO - PROCESS | 1955 | RSS: Main: 239271936 13:49:15 INFO - PROCESS | 1955 | 13:50:07 INFO - PROCESS | 1955 | 1461963007288 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] 13:52:17 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:57363/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57363/tests/dromaeo/cssquery-ext.html) 13:52:19 INFO - PROCESS | 1955 | RSS: Main: 341389312 13:52:19 INFO - PROCESS | 1955 | 13:55:22 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:57363/tests/dromaeo/cssquery-ext.html (next: http://localhost:57363/tests/dromaeo/cssquery-jquery.html) 13:55:22 INFO - PROCESS | 1955 | RSS: Main: 372101120 13:55:22 INFO - PROCESS | 1955 | 13:58:25 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:57363/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57363/tests/dromaeo/cssquery-mootools.html) 13:58:25 INFO - PROCESS | 1955 | RSS: Main: 370454528 13:58:25 INFO - PROCESS | 1955 | 14:01:27 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:57363/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57363/tests/dromaeo/cssquery-prototype.html) 14:01:28 INFO - PROCESS | 1955 | RSS: Main: 368398336 14:01:28 INFO - PROCESS | 1955 | 14:04:31 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:57363/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57363/tests/dromaeo/cssquery-yui.html) 14:04:32 INFO - PROCESS | 1955 | RSS: Main: 339976192 14:04:32 INFO - PROCESS | 1955 | 14:07:35 INFO - PROCESS | 1955 | Cycle 1(1): loaded http://localhost:57363/tests/dromaeo/cssquery-yui.html (next: http://localhost:57363/tests/dromaeo/cssquery-dojo.html) 14:07:35 INFO - PROCESS | 1955 | RSS: Main: 376877056 14:07:35 INFO - PROCESS | 1955 | 14:07:35 INFO - PROCESS | 1955 | __start_tp_report 14:07:35 INFO - PROCESS | 1955 | _x_x_mozilla_page_load 14:07:35 INFO - PROCESS | 1955 | _x_x_mozilla_page_load_details 14:07:35 INFO - PROCESS | 1955 | |i|pagename|runs| 14:07:35 INFO - PROCESS | 1955 | |0;dojo.html;3291.1772056985756;3350.748693847962;3377.341418423407;3387.779794313371;3420.606630237522;5500.477454641813;5607.214989901417;5616.6630002199845;5619.4942455179025;5652.604317697757;5618.325800903887;5642.4357564243655;5695.003374409483;5714.25714657094;5737.483626473617;5103.795848166069;5117.411497677777;5145.871353216162;5157.510036546522;5244.947550524484;5224.399194092671;5238.4499627539135;5258.973705131488;5284.524392804647;5304.92042619361;5088.160453525201;5100.132977393852;5144.74276286188;5196.038732834438;5240.606954478391;5137.6403651744395;5172;5175.171972484384;5180.222966555009;5200.271961925334;5133.948660513384;5161.870953226162;5197.298364720752;5197.402298735656;5200.531952124327;5422.75597598109;5467.535259502947;5590.18942253375;5593.720313984284;5616.606837521375;6143.1092491588915;6151.569390142692;6158.476529494998;6161.322254551996;6176.96911515444;6161.876762464725;6221.4089661482085;6224.439800417984;6225.906611400833;6227.252729672423;5529.502344788948;5663.915041274384;5664.830055098354;5670.206171136044;5673.744681489343;6262.812115636538;6262.906056409158;6265.248170219512;6289.0252010938375;6292.370762923671;6043.8489037773525;6052.394760524;6057.515398768088;6062.42406971337;6075.787347442907;6060.787872424444;6061.3029501607;6144.064619644798;6210.4410603045035;6223.408776166257;5993.430624090704;6039.033754599243;6096.01269589369;6134.386561343919;6144.354842741537;6166.691665416712;6174.289956654952;6179.134921111048;6187.504999600022;6190.876182476325;4776.307435421914;4811.4707382187935;4848.539388758062;4860.805567777249;4905.969746353235;4345.739255644671;4349.021470169187;4378.190034843564;4422.447194100738;4427.712198707074;5585.664860108406;5612.607117501736;5615.157726341082;5615.719214039282;5627.212190293362;6092.664903430265;6170.691465426711;6230.470410015199;6243.282022567463;6243.625382477066;5558.666480011212;5611.6071874969175;5619.7471113799975;5664.0937450007805;5672.773089076473;5298.70857102863;5305.602079844027;5305.965336759372;5313.734313284398;5315.654482458628;6145.340757995227;6181.5981961174175;6262.405071518198;6283.183186185766;6294.055891616203;6225.3774622537385;6232.376762323913;6250.999840025756;6261.655608941552;6264.906026409699;6239.719212635351;6276.435120839146;6282.4974002080635;6287.182666253358;6308.558400911892;5862.677552734641;5949.940500594938;5968.940310596839;6150.323464418821;6153.015517517176;6102.792231004566;6130.795626845359;6167.105769663463;6208.286047104641;6209.59637623553;6048.274207095177;6053.727582258721;6055.212822333068;6109.526129009804;6115.510759139169;5996.2504686914135;6003.339632640494;6042.728077236623;6056.757729690763;6063.363346848694;5728.742206600629;5808.186853840551;5834.358220595646;5894.115882617642;5916;4975.7512124392915;6064.302605200459;6073.696315184311;6078.322614818148;6080.027195648675;4939.851804445872;5629.408912064175;5878.764849405976;5889.381614930542;5904.881902362014 14:07:35 INFO - PROCESS | 1955 | |1;ext.html;515.6055617452648;523.4582207415326;525.5060243371229;537.2478530057919;568.9857753556163;28064.29839254017;38042.80978595107;38903.61096389035;39174;39181.02047448812;42520.78739606305;43343.34984975228;43353;43354.13291734163;43356.349654755206;8472;9555.522223888813;9806.558704858298;9814.312998090136;9837.065478779505;8515.446495977772;11156.219064665476;11379.146564007691;11389.373584452851;11402.030827379682;11378.260413073165;11608.94195529021;11621.070314374872;11624.535018599247;11626.360550169737;8361.665533378658;11173.72065698356;11433.942830285796;11521.135914806424;11710;11183.273087249303;11381.544738210461;11452.484638191301;11460.140489463323;11470.88529114715;38192.236155277016;38692.41961370582;38730.80634596809;38755.806220968996;38768.80615596904;3274.2960263543214;3382.492626106079;3771.8679846205528;3827.7320587558884;4038.172174704208;29028;29112.126636200737;29393;29765.958191463196;29770;2712.2541301142146;2735.9863200684067;2758.613794068832;2793.2737488252997;2817.4505971335598;421039;497395;500120;500538;501470;34064.65935340615;59467.10799338014;59887.401125989054;59968.70015649981;60010.69994650086;35715.82142089273;35780;35850;35855;35867.92396228117;11462.94268528652;15032.62418439526;15040.774388384183;15040.924795376171;15045.473408430653;11567.363794991355;11754.412279385997;11755.177137600456;11766.58816941403;12779.808302875465;7676.2707542784465;7934.841303173905;8091.433599648086;8109.837803243902;8116.553589552514;3163.7469002479747;3328.417526932777;3376.814275214838;3483.494893240466;3501.529357669785;953.8664586957832;960.7742180587557;970.3741086998988;1015.5430056474615;1021.3514418344256;24116.63825042626;29736.702632973826;29760.255993600334;29788.757252303192;30355.696443035285;1038.9220808439245;1164.4759858063906;1194.3729541990351;1201.2672269915345;1235.3946566182506;14911.403543858341;15106.773398399033;15116.924415378073;15132.940694151304;15621;655061;658654;658739;662573;663627;59457.40542594519;60202.69898650479;60219;60267;60534;1214.623466725318;1251.974960500803;1304.9543265985455;1496.3266530061517;1501.2568778454736;28342.716572834008;28825.711742882304;29276.414471710872;29411.411771764873;29939.401211976074;47737.56787296387;47817.28274075822;48213.276800847314;48427.75786121047;48705.07769533839;424440;426027;426636;427019;427413;22619.208327709108;24919.626205607292;25443.745562544136;25693.614595780706;26387.076452324076;40691.593084069966;40737.818403775345;40950;40991;41442;33989.49015764716;34836.129096772784;34938.65061349354;36648.26703465969;37657.55225910307;33109.83445082759;33909.66090339164;36103.27793444169;36567.26865462729;37343.43984840175;29601.407971840872;35768;36163.095922602144;36255.64932208152;36382.8180859094;31849.522257165696;32489;32548.674513254566;32619.673803261663;32656.34687306193;23765.762342376354;23855;24004.87997560001;24057.157999470634;24555.877220614497 14:07:35 INFO - PROCESS | 1955 | |2;jquery.html;390.3403248510017;390.56256992168755;393.9507561554806;398.2890540385413;400.74752905669425;13583.796243056337;20172.19311227549;20918.89540552297;20932.47668808279;21021;15777;17571;19766.901165494153;20444.386668399933;20847.166113355448;5794.507466865311;5986.191864098357;6018.187544681477;6027.819165425044;6037.849053773669;5616.410276920926;5890.116482527612;5915.615484993483;5921.851953701171;5957.523398128139;5866.882662346772;5871.324797648261;5875.88248235035;5882.3529411764675;5887.88224235515;5697.059985102474;5827.155062515953;5900.498457631106;5908.615939963888;5920.052791553331;5110.412302585176;5123.38519377677;5151.5621172200035;5152.175651895679;5180.456052114511;24246.878765606234;24595.770211489355;25899.0935317263;25996.74003259981;26005.349866253495;2216.390492614528;2233.530958498717;2289.8053665438456;2639.551276283017;2713.1996061162004;14604.977651564395;14666.266686665624;14742.336594853257;14931.402743890232;15461.5361539154;1766.2934826069547;1827.1412436154985;1830.7711536057993;1871.7192421136801;1916.1856211110223;117669.41165294118;117931.41034294774;124444;125639;127432;10485.842712359321;10989.560417583367;11155.944220278847;11195.992360687414;11313.321200727984;13944.117544131615;14001.949853761009;14034.157950523002;14053.85946140546;14062.226577538333;6593.80218593443;8128.715494957648;8180;8469.449485783527;8602.569871506399;8732.476051436934;8753.868691969625;8759.737207883774;8790.296776257885;8894.243989260985;6453.645049522322;6601.33986601336;6610.570312929644;6632.668366581652;6703.162104736908;1832.4136276391428;1861.088066847262;1885.4720678210124;1886.1889387563479;1892.687706528435;923.1414784250711;930.8184903943666;974.3033730882453;1056.5034433816095;1061.6337363609543;16657.250423730962;20662.17351305961;21203.15187392518;21260.574788504142;21285.36143915685;865.7748985263751;958.9712308630752;985.6796541124163;1006.0292510176391;1039.3555995282973;11011.559537618567;11128.554857805597;11306.378149201708;11338.60314888975;11599.478023488964;555713;570533;577235;596624;601813;120057;120379.39810300892;121429;122634.38682806528;125110;965.6571916969515;969.5297780576415;1058.6241884131177;1072.587053984206;1076.4994277660926;16302.532772050572;16899.408520701716;17450.9127454367;17930.910345448712;18146.81853181451;48591.274798861195;53313.20030199472;54110.04201785169;54525.45474545362;54797.1780423302;71916.64041679759;79183;80631.59684201777;81601;83523.5823820877;7684.577348245676;7846.411519136202;7942.944209800155;8025.15735847751;8130.308923741311;8917.051036674708;11904.583339583072;12117.454714537691;12335.814962775745;12386.62840114798;10410;10504.842427363748;10550.671447164388;10650.627228046982;10732.946335268585;10263.794724105626;10364.170866330525;10376.16990640762;10615;10679.786404272027;10427.895721042996;10568.524416401435;10973.23187376876;11036.668899933014;11101.5559377624;7886.290233878978;7929.246721561442;8030.959845200737;8055.395845311742;8268;8632.352573557135;8928.330375222014;10477.633282835064;10742.83885741699;10797.514111864828 14:07:35 INFO - PROCESS | 1955 | |3;mootools.html;1316.0655934286656;1391.7773156294995;1443.3216388297496;1475.8155230596176;1712.2551690014836;7802.2587854153835;8682.522461264627;8879.289656827454;8884.200421962023;8885.555722213896;8619.310455163588;8825.117488251171;8826.470411775297;8827.029026807046;8859.64561417544;2141.15424407359;2153.1495059451545;2220.6891035255076;2221.5445833604153;2465.2234546118007;2221.7333919929633;2408.205292253552;2498.600223964166;2503.7245902950663;2505.1357281737774;2418.9395265118424;2444.9022039118413;2455.72986971433;2460;2460.237326428804;2113.587850369179;2118.834640947488;2131.4138611881704;2421.624648179535;2451.252368027751;2388.1283331584023;2467.9136230231857;2475.4553998120386;2475.492524032569;2480.1071614219045;8568.400211985163;8675.522846243517;8686.218240358397;8692.478451292942;8720.651173953098;4586.990862010352;4699.295105734133;5108.106081435734;5420.810271640474;5432.076546986982;3464.428369319059;3590.7666001710068;3674.1365779041903;3678.9264214715554;3708.592054873962;1024.1653052761976;1025.8050970315692;1036.9526777954245;1048.3133547526381;1048.5281623269484;11702.590409335633;11722.824157637642;11730.35483048432;11746.765064698744;11751.353675547842;4841.467438581753;4854.417469903634;4876.926846097312;4923.359963204761;5002.799888004439;9992.300538962349;10025.097741203326;10040.79918401628;10053.899461005443;10092.545835437422;5435.537979271806;5474.178873169057;5477.589180811455;5486.862828429321;5490.917636235459;4842.5157484251995;4887.975560122248;4939.728314942641;4976.054549635558;4986.700797952134;4014.337634290368;4064.349704047397;4129.29801933672;4161.521425036099;4195.1819395217635;1356.9796453053211;1359.429039803285;1362.5845408229286;1365.378752667534;1375.917444953306;851.7359618518155;873.1312344217417;902.5622573052065;953.4994128082812;962.6438217859297;4482.5069242383315;5928.525717942641;5982.73077711504;5992.8801423972145;6173.41352571505;864.8140649760331;930.1303281431883;946.3564775952281;950.2398081534744;1030.737162023694;3577.94633080504;4015.859444919472;4089.795510224477;4103.815328310233;4104.692148088905;10061.949690251502;10445.947770261251;10546.57813687444;10550.947245263724;10558.63044793444;10408.271421000458;10498.370097794157;10521.053105220566;10531.368117912949;10537.578496860193;7900.644470998819;8127.593620319079;8132.430729848972;8136.755897323208;8138.3896207784655;5682.772689092555;5937.910931335947;6014.518838492998;6073.544484163617;6073.787417440369;9639.421634701941;9740.610375584898;10007.049330313608;10030.144423281716;10038.949805250926;8923.643054277756;9878.110970012733;10198.33710808795;10363.844542332023;10397.948010259901;6577.210734711865;7547.660355283915;7591.316781489692;7699.538027718355;7706.730264440717;8113.391495637969;8245.13426090252;8267.090620031911;8281.171882811912;8321.292690121407;7412.851742965218;7507.024086868891;7531.435142364235;7578.621068946641;7610.010698609145;7360.558366498028;7373.668184931798;7435.256474352522;7487.026686530716;7530.28462296081;7010.404115650227;7032.424147731411;7057.611831349325;7097.396721278749;7106;4858.392700912386;4912.312276281252;4927.531884470969;4928.704277743347;5023.572996295356;4895.877603059809;4897.877553061202;4951.059298733229;5065.56942659878;5147.150720131136 14:07:35 INFO - PROCESS | 1955 | |4;prototype.html;269.50007735650365;316.5252121817274;317.54115303386607;326.1894192930568;330.68750031220475;5137.409197942233;5202.427732949377;5218.817341393052;5349.8127565535215;5412.242286079952;4731.33761273422;5121.897562048757;5192.71440070796;5238.973805130979;5253.474652534745;3765.585785563586;3878.0997311317465;3930.941035884464;3955.307821131305;4012.8996775080564;3309.420851351016;3678.7240956928204;4050.8784736457956;4083;4103.215101430887;3154.5268209768487;4024.8792536223814;4027.174429242013;4051.3315302974975;4059.4925634295714;3204.279037216631;3817.7136714746366;3880.556762506942;4030.8109107813266;4071.8982025449304;3013.6835632258517;3126.4059828632485;3479.7564170508076;3504.56192975878;3530.082178633548;4995.325631039799;4998.225275082368;5032.4967503249745;5043.5460808527405;5059.519345662156;4233.470816147951;4392.582704643054;4403.955960440387;4567.657425693086;4579.335996280554;4570.7029043112425;5504.944950550484;5515.531179849718;5523.613347065707;5527.198556209327;3256.788308759923;3813.809309534512;3814.7901865397394;3834.9041273968096;3842.269968705937;4865.8783530411665;5601.500384074402;5655.4061823508355;5729.6562206267345;5816.069428891357;3342.8133012780113;3774.188549461824;3842.711796615209;3849.7305188637088;3915.8629447969183;3319.535265062893;3920;3927.5286965564314;3939.1333906540517;3951;4534.7052441591195;4535.092981403733;4538.432695913011;4549.3176023596725;4560.794764235551;4656.3946686930485;5202.5432878794;5572.3870374258795;5585.525230355465;5587.776488940397;1369.8424831017774;1410.5204230561453;1411.56947131126;1428.0360756489329;1431.8568143185807;751.6692655231681;754.9242329680184;903.584351198443;908.4322298563397;921.9631214751469;4795.760211989386;5088.542031217208;5161.612879034087;5652.773889044474;5709.885802283931;5487.451254874481;6067.635941843503;6207.317195108534;6228.283747369111;6231.22109736283;5451.618386712892;5647.887042259132;5659;5712.943105525491;5736.627119237236;2750.3674154944074;3002.7297543221216;3256.2673398485153;3259.885903993349;3290.8848190313224;6099.878002439927;6511.804645860631;6518.185226846645;6524.641144737085;6537.967310163419;4807.6394270429855;5736.971315143398;6217.378262173836;6367.61794292344;6391.6258004529245;5762.798302059492;5791.681457519792;5902.586818922634;6080.8750381179625;6477.740890364427;5221.321228240457;6054.878902421839;6139.938600614116;6218.53360997936;6262.060690896402;5068.645194836474;6101.603395779438;6159.568830182024;6163.599366041193;6188.319284878749;5735.225744524436;6006.768612434528;6136.7852125175405;6476.514261430506;6488.351164883474;3469.7918124912585;3573.857045718142;4065.7560546367313;4122.866211791781;4140.482439695038;5451.264079349396;6028.728707208098;6235.937640623717;6248.593841400295;6251.187345645037;4925.778339974638;5784.652920824764;6086.147939288415;6097.176881120992;6115.052166914135;4960.313493412257;5548.335499350098;5605.523530499953;5892.734826932712;6103.481204097701;4572.849517399093;5202.800277829032;5916.733746981482;5928.733207005609;5934.169216309634;5165.586753059822;5170.767315470737;5208.5519805691265;5641.210230567642;5734.455226753452;4657.627389808739;5518.91721624184;5536.390996990247;5579.693116878449;5590.189422533791 14:07:35 INFO - PROCESS | 1955 | |5;yui.html;731.151863837948;741.933226009659;748.248010749197;757.3297631596038;794.5749024272016;14645.853541464596;14744.557663270092;15094.47169349073;15114.09315441073;15123.546293611182;14006.159630422182;14064.367103480343;14079.718405631882;14082.014259001873;14093.506727264548;743.9330460258576;769.4075561817398;905.7192270396198;941.6280569175154;944.5135755086136;942.8632848237002;947.1901524196782;947.8909925358603;951.7763325618474;952.5665822050985;998.5805961496189;1007.6120693532969;1008.8234558952191;1009.0716540782498;1009.7425156585098;760.5588758520045;953.5470651440545;985.5269470654055;988.298308201178;989.3173710140052;740.9073865766779;744.4714252880461;744.8473063022068;747.1818358896999;748.1807420874134;14752.631184220372;14769.409223631043;14793.40826366944;14819.110853348726;14828;12286.13997020209;12437.315947622878;12547.058970577244;12581.93709031449;12799;9949.154321882648;9965.701028969142;10129.797404051951;10473.31923424975;10509.369437833679;3468.500654995834;3483.076984599083;3497.842597083137;3553;3553.822308884571;83729.8135093249;83903.58048209781;84784;85156.57421712852;86044;9424;9555.569999350046;9597.23222142227;9616.374935629161;9701.223902087817;22830.543389132123;22834.42913927165;22839.429014274778;22852.885735571548;22855.428614284443;7347.669354879044;7405.1334864928285;7448.851022979618;7458;7593.772186834404;4029.222054793844;4048.149888523384;4055.270051390778;4075.857344992911;4372.453443319585;3509.596396414394;3635.8727444539313;3639.1629925117372;3642.7267954903486;3652.4521321801412;598.5510866849862;677.833930686975;678.6538865178794;717.2863001313726;725.1443296909591;514.354485121178;550.6585916731572;566.5071387892477;601.9036954087412;607.1378642327905;8264.21489958453;8287;8447.788805279793;8488.490690558463;8547.829043419097;485.4490153675604;552.3923683947705;591.721890711365;597.6294697287623;641.0448431836624;3824.158685089276;3850.4609354690365;3873.283442563135;3889.2610404023144;3935.8622448214746;96543.0345696548;96802;97961.51019245;101878.98121018843;103162.48418757858;60685.393146068855;75534.11164720925;78025.60987195028;80506.5974670123;81389.59305203435;75196.62401688065;75301.24698753053;75466.24533754592;75784;76530.23469765342;7554.2823431773895;7561.546307221584;7630.236976302325;7870.29167374939;8001.119876813662;47029.294560582304;47577.286340704224;47651.76174119107;48971;49015;64252.35747642651;69119.30880691129;69622.30377696158;69643.25891852744;70883;10158.695239142837;10463.267571270242;10554.525046372779;10565.894341056799;10920.563177472812;10953.34279943174;10979;11093.445327733742;11528.481218345363;12096.818547721967;10237.232207584311;10403.687889363331;10730.034296913314;10735.194860385345;10741.731456713645;10439.58241670355;10656.467176641292;10678.57285708563;10892.237543371888;10932.289401188897;10346.430946298025;10491.213159012952;10566.207534434794;10625.36247825102;10712.57149714034;8836.160564746338;8908.198262156437;9113.589888455168;9218.21645160169;9492.03403549644;8747.65009399617;8830.205281524693;8891.110888911056;9022.503762292874;9108.089191080839 14:07:35 INFO - PROCESS | 1955 | __end_tp_report 14:07:35 INFO - PROCESS | 1955 | __start_cc_report 14:07:35 INFO - PROCESS | 1955 | _x_x_mozilla_cycle_collect,1197 14:07:35 INFO - PROCESS | 1955 | __end_cc_report 14:07:35 INFO - PROCESS | 1955 | __startTimestamp1461964055927__endTimestamp 14:07:35 INFO - PROCESS | 1955 | 14:07:35 INFO - PROCESS | 1955 | ------- Summary: start ------- 14:07:35 INFO - PROCESS | 1955 | Number of tests: 6 14:07:35 INFO - PROCESS | 1955 | 14:07:35 INFO - PROCESS | 1955 | [#0] dojo.html Cycles:165 Average:5672.87 Median:5878.76 stddev:632.29 (10.8%) stddev-sans-first:605.99 14:07:35 INFO - PROCESS | 1955 | Values: 3291.2 3350.7 3377.3 3387.8 3420.6 5500.5 5607.2 5616.7 5619.5 5652.6 5618.3 5642.4 5695.0 5714.3 5737.5 5103.8 5117.4 5145.9 5157.5 5244.9 5224.4 5238.4 5259.0 5284.5 5304.9 5088.2 5100.1 5144.7 5196.0 5240.6 5137.6 5172.0 5175.2 5180.2 5200.3 5133.9 5161.9 5197.3 5197.4 5200.5 5422.8 5467.5 5590.2 5593.7 5616.6 6143.1 6151.6 6158.5 6161.3 6177.0 6161.9 6221.4 6224.4 6225.9 6227.3 5529.5 5663.9 5664.8 5670.2 5673.7 6262.8 6262.9 6265.2 6289.0 6292.4 6043.8 6052.4 6057.5 6062.4 6075.8 6060.8 6061.3 6144.1 6210.4 6223.4 5993.4 6039.0 6096.0 6134.4 6144.4 6166.7 6174.3 6179.1 6187.5 6190.9 4776.3 4811.5 4848.5 4860.8 4906.0 4345.7 4349.0 4378.2 4422.4 4427.7 5585.7 5612.6 5615.2 5615.7 5627.2 6092.7 6170.7 6230.5 6243.3 6243.6 5558.7 5611.6 5619.7 5664.1 5672.8 5298.7 5305.6 5306.0 5313.7 5315.7 6145.3 6181.6 6262.4 6283.2 6294.1 6225.4 6232.4 6251.0 6261.7 6264.9 6239.7 6276.4 6282.5 6287.2 6308.6 5862.7 5949.9 5968.9 6150.3 6153.0 6102.8 6130.8 6167.1 6208.3 6209.6 6048.3 6053.7 6055.2 6109.5 6115.5 5996.3 6003.3 6042.7 6056.8 6063.4 5728.7 5808.2 5834.4 5894.1 5916.0 4975.8 6064.3 6073.7 6078.3 6080.0 4939.9 5629.4 5878.8 5889.4 5904.9 14:07:35 INFO - PROCESS | 1955 | 14:07:35 INFO - PROCESS | 1955 | [#1] ext.html Cycles:180 Average:64455.39 Median:18774.08 stddev:142731.86 (760.3%) stddev-sans-first:143051.52 14:07:35 INFO - PROCESS | 1955 | Values: 515.6 523.5 525.5 537.2 569.0 28064.3 38042.8 38903.6 39174.0 39181.0 42520.8 43343.3 43353.0 43354.1 43356.3 8472.0 9555.5 9806.6 9814.3 9837.1 8515.4 11156.2 11379.1 11389.4 11402.0 11378.3 11608.9 11621.1 11624.5 11626.4 8361.7 11173.7 11433.9 11521.1 11710.0 11183.3 11381.5 11452.5 11460.1 11470.9 38192.2 38692.4 38730.8 38755.8 38768.8 3274.3 3382.5 3771.9 3827.7 4038.2 29028.0 29112.1 29393.0 29766.0 29770.0 2712.3 2736.0 2758.6 2793.3 2817.5 421039.0 497395.0 500120.0 500538.0 501470.0 34064.7 59467.1 59887.4 59968.7 60010.7 35715.8 35780.0 35850.0 35855.0 35867.9 11462.9 15032.6 15040.8 15040.9 15045.5 11567.4 11754.4 11755.2 11766.6 12779.8 7676.3 7934.8 8091.4 8109.8 8116.6 3163.7 3328.4 3376.8 3483.5 3501.5 953.9 960.8 970.4 1015.5 1021.4 24116.6 29736.7 29760.3 29788.8 30355.7 1038.9 1164.5 1194.4 1201.3 1235.4 14911.4 15106.8 15116.9 15132.9 15621.0 655061.0 658654.0 658739.0 662573.0 663627.0 59457.4 60202.7 60219.0 60267.0 60534.0 1214.6 1252.0 1305.0 1496.3 1501.3 28342.7 28825.7 29276.4 29411.4 29939.4 47737.6 47817.3 48213.3 48427.8 48705.1 424440.0 426027.0 426636.0 427019.0 427413.0 22619.2 24919.6 25443.7 25693.6 26387.1 40691.6 40737.8 40950.0 40991.0 41442.0 33989.5 34836.1 34938.7 36648.3 37657.6 33109.8 33909.7 36103.3 36567.3 37343.4 29601.4 35768.0 36163.1 36255.6 36382.8 31849.5 32489.0 32548.7 32619.7 32656.3 23765.8 23855.0 24004.9 24057.2 24555.9 14:07:35 INFO - PROCESS | 1955 | 14:07:35 INFO - PROCESS | 1955 | [#2] jquery.html Cycles:180 Average:34459.71 Median:2261.67 stddev:97062.09 (4291.6%) stddev-sans-first:97300.66 14:07:35 INFO - PROCESS | 1955 | Values: 390.3 390.6 394.0 398.3 400.7 13583.8 20172.2 20918.9 20932.5 21021.0 15777.0 17571.0 19766.9 20444.4 20847.2 5794.5 5986.2 6018.2 6027.8 6037.8 5616.4 5890.1 5915.6 5921.9 5957.5 5866.9 5871.3 5875.9 5882.4 5887.9 5697.1 5827.2 5900.5 5908.6 5920.1 5110.4 5123.4 5151.6 5152.2 5180.5 24246.9 24595.8 25899.1 25996.7 26005.3 2216.4 2233.5 2289.8 2639.6 2713.2 14605.0 14666.3 14742.3 14931.4 15461.5 1766.3 1827.1 1830.8 1871.7 1916.2 117669.4 117931.4 124444.0 125639.0 127432.0 10485.8 10989.6 11155.9 11196.0 11313.3 13944.1 14001.9 14034.2 14053.9 14062.2 6593.8 8128.7 8180.0 8469.4 8602.6 8732.5 8753.9 8759.7 8790.3 8894.2 6453.6 6601.3 6610.6 6632.7 6703.2 1832.4 1861.1 1885.5 1886.2 1892.7 923.1 930.8 974.3 1056.5 1061.6 16657.3 20662.2 21203.2 21260.6 21285.4 865.8 959.0 985.7 1006.0 1039.4 11011.6 11128.6 11306.4 11338.6 11599.5 555713.0 570533.0 577235.0 596624.0 601813.0 120057.0 120379.4 121429.0 122634.4 125110.0 965.7 969.5 1058.6 1072.6 1076.5 16302.5 16899.4 17450.9 17930.9 18146.8 48591.3 53313.2 54110.0 54525.5 54797.2 71916.6 79183.0 80631.6 81601.0 83523.6 7684.6 7846.4 7942.9 8025.2 8130.3 8917.1 11904.6 12117.5 12335.8 12386.6 10410.0 10504.8 10550.7 10650.6 10732.9 10263.8 10364.2 10376.2 10615.0 10679.8 10427.9 10568.5 10973.2 11036.7 11101.6 7886.3 7929.2 8031.0 8055.4 8268.0 8632.4 8928.3 10477.6 10742.8 10797.5 14:07:35 INFO - PROCESS | 1955 | 14:07:35 INFO - PROCESS | 1955 | [#3] mootools.html Cycles:180 Average:5711.50 Median:4896.88 stddev:3172.93 (64.8%) stddev-sans-first:3164.63 14:07:35 INFO - PROCESS | 1955 | Values: 1316.1 1391.8 1443.3 1475.8 1712.3 7802.3 8682.5 8879.3 8884.2 8885.6 8619.3 8825.1 8826.5 8827.0 8859.6 2141.2 2153.1 2220.7 2221.5 2465.2 2221.7 2408.2 2498.6 2503.7 2505.1 2418.9 2444.9 2455.7 2460.0 2460.2 2113.6 2118.8 2131.4 2421.6 2451.3 2388.1 2467.9 2475.5 2475.5 2480.1 8568.4 8675.5 8686.2 8692.5 8720.7 4587.0 4699.3 5108.1 5420.8 5432.1 3464.4 3590.8 3674.1 3678.9 3708.6 1024.2 1025.8 1037.0 1048.3 1048.5 11702.6 11722.8 11730.4 11746.8 11751.4 4841.5 4854.4 4876.9 4923.4 5002.8 9992.3 10025.1 10040.8 10053.9 10092.5 5435.5 5474.2 5477.6 5486.9 5490.9 4842.5 4888.0 4939.7 4976.1 4986.7 4014.3 4064.3 4129.3 4161.5 4195.2 1357.0 1359.4 1362.6 1365.4 1375.9 851.7 873.1 902.6 953.5 962.6 4482.5 5928.5 5982.7 5992.9 6173.4 864.8 930.1 946.4 950.2 1030.7 3577.9 4015.9 4089.8 4103.8 4104.7 10061.9 10445.9 10546.6 10550.9 10558.6 10408.3 10498.4 10521.1 10531.4 10537.6 7900.6 8127.6 8132.4 8136.8 8138.4 5682.8 5937.9 6014.5 6073.5 6073.8 9639.4 9740.6 10007.0 10030.1 10038.9 8923.6 9878.1 10198.3 10363.8 10397.9 6577.2 7547.7 7591.3 7699.5 7706.7 8113.4 8245.1 8267.1 8281.2 8321.3 7412.9 7507.0 7531.4 7578.6 7610.0 7360.6 7373.7 7435.3 7487.0 7530.3 7010.4 7032.4 7057.6 7097.4 7106.0 4858.4 4912.3 4927.5 4928.7 5023.6 4895.9 4897.9 4951.1 5065.6 5147.2 14:07:35 INFO - PROCESS | 1955 | 14:07:35 INFO - PROCESS | 1955 | [#4] prototype.html Cycles:180 Average:4646.44 Median:5168.18 stddev:1501.55 (29.1%) stddev-sans-first:1469.38 14:07:35 INFO - PROCESS | 1955 | Values: 269.5 316.5 317.5 326.2 330.7 5137.4 5202.4 5218.8 5349.8 5412.2 4731.3 5121.9 5192.7 5239.0 5253.5 3765.6 3878.1 3930.9 3955.3 4012.9 3309.4 3678.7 4050.9 4083.0 4103.2 3154.5 4024.9 4027.2 4051.3 4059.5 3204.3 3817.7 3880.6 4030.8 4071.9 3013.7 3126.4 3479.8 3504.6 3530.1 4995.3 4998.2 5032.5 5043.5 5059.5 4233.5 4392.6 4404.0 4567.7 4579.3 4570.7 5504.9 5515.5 5523.6 5527.2 3256.8 3813.8 3814.8 3834.9 3842.3 4865.9 5601.5 5655.4 5729.7 5816.1 3342.8 3774.2 3842.7 3849.7 3915.9 3319.5 3920.0 3927.5 3939.1 3951.0 4534.7 4535.1 4538.4 4549.3 4560.8 4656.4 5202.5 5572.4 5585.5 5587.8 1369.8 1410.5 1411.6 1428.0 1431.9 751.7 754.9 903.6 908.4 922.0 4795.8 5088.5 5161.6 5652.8 5709.9 5487.5 6067.6 6207.3 6228.3 6231.2 5451.6 5647.9 5659.0 5712.9 5736.6 2750.4 3002.7 3256.3 3259.9 3290.9 6099.9 6511.8 6518.2 6524.6 6538.0 4807.6 5737.0 6217.4 6367.6 6391.6 5762.8 5791.7 5902.6 6080.9 6477.7 5221.3 6054.9 6139.9 6218.5 6262.1 5068.6 6101.6 6159.6 6163.6 6188.3 5735.2 6006.8 6136.8 6476.5 6488.4 3469.8 3573.9 4065.8 4122.9 4140.5 5451.3 6028.7 6235.9 6248.6 6251.2 4925.8 5784.7 6086.1 6097.2 6115.1 4960.3 5548.3 5605.5 5892.7 6103.5 4572.8 5202.8 5916.7 5928.7 5934.2 5165.6 5170.8 5208.6 5641.2 5734.5 4657.6 5518.9 5536.4 5579.7 5590.2 14:07:35 INFO - PROCESS | 1955 | 14:07:35 INFO - PROCESS | 1955 | [#5] yui.html Cycles:180 Average:18558.21 Median:598.09 stddev:26752.22 (4472.9%) stddev-sans-first:26793.78 14:07:35 INFO - PROCESS | 1955 | Values: 731.2 741.9 748.2 757.3 794.6 14645.9 14744.6 15094.5 15114.1 15123.5 14006.2 14064.4 14079.7 14082.0 14093.5 743.9 769.4 905.7 941.6 944.5 942.9 947.2 947.9 951.8 952.6 998.6 1007.6 1008.8 1009.1 1009.7 760.6 953.5 985.5 988.3 989.3 740.9 744.5 744.8 747.2 748.2 14752.6 14769.4 14793.4 14819.1 14828.0 12286.1 12437.3 12547.1 12581.9 12799.0 9949.2 9965.7 10129.8 10473.3 10509.4 3468.5 3483.1 3497.8 3553.0 3553.8 83729.8 83903.6 84784.0 85156.6 86044.0 9424.0 9555.6 9597.2 9616.4 9701.2 22830.5 22834.4 22839.4 22852.9 22855.4 7347.7 7405.1 7448.9 7458.0 7593.8 4029.2 4048.1 4055.3 4075.9 4372.5 3509.6 3635.9 3639.2 3642.7 3652.5 598.6 677.8 678.7 717.3 725.1 514.4 550.7 566.5 601.9 607.1 8264.2 8287.0 8447.8 8488.5 8547.8 485.4 552.4 591.7 597.6 641.0 3824.2 3850.5 3873.3 3889.3 3935.9 96543.0 96802.0 97961.5 101879.0 103162.5 60685.4 75534.1 78025.6 80506.6 81389.6 75196.6 75301.2 75466.2 75784.0 76530.2 7554.3 7561.5 7630.2 7870.3 8001.1 47029.3 47577.3 47651.8 48971.0 49015.0 64252.4 69119.3 69622.3 69643.3 70883.0 10158.7 10463.3 10554.5 10565.9 10920.6 10953.3 10979.0 11093.4 11528.5 12096.8 10237.2 10403.7 10730.0 10735.2 10741.7 10439.6 10656.5 10678.6 10892.2 10932.3 10346.4 10491.2 10566.2 10625.4 10712.6 8836.2 8908.2 9113.6 9218.2 9492.0 8747.7 8830.2 8891.1 9022.5 9108.1 14:07:35 INFO - PROCESS | 1955 | -------- Summary: end -------- 14:07:35 INFO - PROCESS | 1955 | 14:07:36 INFO - TEST-INFO | 1955: exit 0 14:07:36 INFO - TEST-OK | dromaeo_css | took 1115563ms 14:07:36 INFO - TEST-START | kraken 14:07:36 INFO - operating with platform_type : mac_ 14:07:36 INFO - Initialising browser for kraken test... 14:07:36 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmp8VGn9i/profile http://localhost:57363/getInfo.html) 14:07:38 INFO - PROCESS | 1958 | __metrics Screen width/height:1600/1200 14:07:38 INFO - PROCESS | 1958 | colorDepth:24 14:07:38 INFO - PROCESS | 1958 | Browser inner width/height: 1024/689 14:07:38 INFO - PROCESS | 1958 | __metrics 14:07:38 INFO - PROCESS | 1958 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:07:38 ERROR - PROCESS | 1958 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:07:39 INFO - TEST-INFO | 1958: exit 0 14:07:39 INFO - Browser initialized. 14:07:39 INFO - Running cycle 1/1 for kraken test... 14:07:39 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/tmp8VGn9i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:07:51 INFO - PROCESS | 1960 | RSS: Main: 239616000 14:07:51 INFO - PROCESS | 1960 | 14:07:53 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57363/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57363/tests/kraken/driver.html?testName=audio-beat-detection) 14:07:54 INFO - PROCESS | 1960 | RSS: Main: 286789632 14:07:54 INFO - PROCESS | 1960 | 14:07:57 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57363/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57363/tests/kraken/driver.html?testName=audio-dft) 14:07:57 INFO - PROCESS | 1960 | RSS: Main: 295755776 14:07:57 INFO - PROCESS | 1960 | 14:08:01 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57363/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57363/tests/kraken/driver.html?testName=audio-fft) 14:08:02 INFO - PROCESS | 1960 | RSS: Main: 382451712 14:08:02 INFO - PROCESS | 1960 | 14:08:04 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57363/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57363/tests/kraken/driver.html?testName=audio-oscillator) 14:08:04 INFO - PROCESS | 1960 | RSS: Main: 280842240 14:08:04 INFO - PROCESS | 1960 | 14:08:06 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57363/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57363/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:08:07 INFO - PROCESS | 1960 | RSS: Main: 285622272 14:08:07 INFO - PROCESS | 1960 | 14:08:10 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57363/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57363/tests/kraken/driver.html?testName=imaging-darkroom) 14:08:10 INFO - PROCESS | 1960 | RSS: Main: 321691648 14:08:10 INFO - PROCESS | 1960 | 14:08:13 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57363/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57363/tests/kraken/driver.html?testName=imaging-desaturate) 14:08:14 INFO - PROCESS | 1960 | RSS: Main: 323022848 14:08:14 INFO - PROCESS | 1960 | 14:08:17 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57363/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57363/tests/kraken/driver.html?testName=json-parse-financial) 14:08:18 INFO - PROCESS | 1960 | RSS: Main: 320483328 14:08:18 INFO - PROCESS | 1960 | 14:08:19 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57363/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57363/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:08:20 INFO - PROCESS | 1960 | RSS: Main: 313151488 14:08:20 INFO - PROCESS | 1960 | 14:08:22 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57363/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57363/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:08:22 INFO - PROCESS | 1960 | RSS: Main: 320466944 14:08:22 INFO - PROCESS | 1960 | 14:08:24 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57363/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57363/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:08:25 INFO - PROCESS | 1960 | RSS: Main: 321769472 14:08:25 INFO - PROCESS | 1960 | 14:08:28 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57363/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57363/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:08:29 INFO - PROCESS | 1960 | RSS: Main: 326688768 14:08:29 INFO - PROCESS | 1960 | 14:08:31 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57363/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57363/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:08:32 INFO - PROCESS | 1960 | RSS: Main: 324554752 14:08:32 INFO - PROCESS | 1960 | 14:08:34 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:57363/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57363/tests/kraken/driver.html?testName=ai-astar) 14:08:34 INFO - PROCESS | 1960 | RSS: Main: 389988352 14:08:34 INFO - PROCESS | 1960 | 14:08:34 INFO - PROCESS | 1960 | __start_tp_report 14:08:34 INFO - PROCESS | 1960 | _x_x_mozilla_page_load 14:08:34 INFO - PROCESS | 1960 | _x_x_mozilla_page_load_details 14:08:34 INFO - PROCESS | 1960 | |i|pagename|runs| 14:08:34 INFO - PROCESS | 1960 | |0;ai-astar;114;112;109;112;113;113;113;112;104;113 14:08:34 INFO - PROCESS | 1960 | |1;audio-beat-detection;163;123;145;169;120;141;140;120;171;118 14:08:34 INFO - PROCESS | 1960 | |2;audio-dft;187;189;184;185;186;186;185;188;185;186 14:08:34 INFO - PROCESS | 1960 | |3;audio-fft;80;76;90;95;65;83;84;110;86;55 14:08:34 INFO - PROCESS | 1960 | |4;audio-oscillator;94;94;138;78;80;79;125;81;77;127 14:08:34 INFO - PROCESS | 1960 | |5;imaging-gaussian-blur;89;85;89;86;87;85;89;85;89;84 14:08:34 INFO - PROCESS | 1960 | |6;imaging-darkroom;96;93;94;93;93;93;94;93;106;149 14:08:34 INFO - PROCESS | 1960 | |7;imaging-desaturate;83;85;84;85;101;111;115;137;134;87 14:08:34 INFO - PROCESS | 1960 | |8;json-parse-financial;60;58;58;58;60;61;61;80;59;57 14:08:34 INFO - PROCESS | 1960 | |9;json-stringify-tinderbox;64;67;81;74;70;69;70;69;69;69 14:08:34 INFO - PROCESS | 1960 | |10;stanford-crypto-aes;83;82;83;81;81;82;81;81;82;84 14:08:34 INFO - PROCESS | 1960 | |11;stanford-crypto-ccm;155;158;151;136;135;138;119;144;177;156 14:08:34 INFO - PROCESS | 1960 | |12;stanford-crypto-pbkdf2;173;170;169;219;168;168;168;167;158;169 14:08:34 INFO - PROCESS | 1960 | |13;stanford-crypto-sha256-iterative;69;68;70;70;75;98;98;94;72;66 14:08:34 INFO - PROCESS | 1960 | __end_tp_report 14:08:34 INFO - PROCESS | 1960 | __start_cc_report 14:08:34 INFO - PROCESS | 1960 | _x_x_mozilla_cycle_collect,1982 14:08:34 INFO - PROCESS | 1960 | __end_cc_report 14:08:34 INFO - PROCESS | 1960 | __startTimestamp1461964114689__endTimestamp 14:08:34 INFO - PROCESS | 1960 | 14:08:34 INFO - PROCESS | 1960 | ------- Summary: start ------- 14:08:34 INFO - PROCESS | 1960 | Number of tests: 14 14:08:34 INFO - PROCESS | 1960 | 14:08:34 INFO - PROCESS | 1960 | [#0] ai-astar Cycles:10 Average:111.50 Median:113.00 stddev:2.95 (2.6%) stddev-sans-first:2.99 14:08:34 INFO - PROCESS | 1960 | Values: 114.0 112.0 109.0 112.0 113.0 113.0 113.0 112.0 104.0 113.0 14:08:34 INFO - PROCESS | 1960 | 14:08:34 INFO - PROCESS | 1960 | [#1] audio-beat-detection Cycles:10 Average:141.00 Median:143.00 stddev:20.87 (14.6%) stddev-sans-first:20.56 14:08:34 INFO - PROCESS | 1960 | Values: 163.0 123.0 145.0 169.0 120.0 141.0 140.0 120.0 171.0 118.0 14:08:34 INFO - PROCESS | 1960 | 14:08:34 INFO - PROCESS | 1960 | [#2] audio-dft Cycles:10 Average:186.10 Median:186.00 stddev:1.52 (0.8%) stddev-sans-first:1.58 14:08:34 INFO - PROCESS | 1960 | Values: 187.0 189.0 184.0 185.0 186.0 186.0 185.0 188.0 185.0 186.0 14:08:34 INFO - PROCESS | 1960 | 14:08:34 INFO - PROCESS | 1960 | [#3] audio-fft Cycles:10 Average:82.40 Median:83.50 stddev:15.25 (18.3%) stddev-sans-first:16.16 14:08:34 INFO - PROCESS | 1960 | Values: 80.0 76.0 90.0 95.0 65.0 83.0 84.0 110.0 86.0 55.0 14:08:34 INFO - PROCESS | 1960 | 14:08:34 INFO - PROCESS | 1960 | [#4] audio-oscillator Cycles:10 Average:97.30 Median:79.50 stddev:23.60 (29.7%) stddev-sans-first:25.00 14:08:34 INFO - PROCESS | 1960 | Values: 94.0 94.0 138.0 78.0 80.0 79.0 125.0 81.0 77.0 127.0 14:08:34 INFO - PROCESS | 1960 | 14:08:34 INFO - PROCESS | 1960 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:88.00 stddev:2.04 (2.3%) stddev-sans-first:2.01 14:08:34 INFO - PROCESS | 1960 | Values: 89.0 85.0 89.0 86.0 87.0 85.0 89.0 85.0 89.0 84.0 14:08:34 INFO - PROCESS | 1960 | 14:08:34 INFO - PROCESS | 1960 | [#6] imaging-darkroom Cycles:10 Average:100.40 Median:93.00 stddev:17.54 (18.9%) stddev-sans-first:18.53 14:08:34 INFO - PROCESS | 1960 | Values: 96.0 93.0 94.0 93.0 93.0 93.0 94.0 93.0 106.0 149.0 14:08:34 INFO - PROCESS | 1960 | 14:08:34 INFO - PROCESS | 1960 | [#7] imaging-desaturate Cycles:10 Average:102.20 Median:83.50 stddev:21.05 (25.2%) stddev-sans-first:21.15 14:08:34 INFO - PROCESS | 1960 | Values: 83.0 85.0 84.0 85.0 101.0 111.0 115.0 137.0 134.0 87.0 14:08:34 INFO - PROCESS | 1960 | 14:08:34 INFO - PROCESS | 1960 | [#8] json-parse-financial Cycles:10 Average:61.20 Median:60.00 stddev:6.75 (11.2%) stddev-sans-first:7.14 14:08:34 INFO - PROCESS | 1960 | Values: 60.0 58.0 58.0 58.0 60.0 61.0 61.0 80.0 59.0 57.0 14:08:34 INFO - PROCESS | 1960 | 14:08:34 INFO - PROCESS | 1960 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:69.50 stddev:4.54 (6.5%) stddev-sans-first:4.23 14:08:34 INFO - PROCESS | 1960 | Values: 64.0 67.0 81.0 74.0 70.0 69.0 70.0 69.0 69.0 69.0 14:08:34 INFO - PROCESS | 1960 | 14:08:34 INFO - PROCESS | 1960 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:82.00 stddev:1.05 (1.3%) stddev-sans-first:1.05 14:08:34 INFO - PROCESS | 1960 | Values: 83.0 82.0 83.0 81.0 81.0 82.0 81.0 81.0 82.0 84.0 14:08:34 INFO - PROCESS | 1960 | 14:08:34 INFO - PROCESS | 1960 | [#11] stanford-crypto-ccm Cycles:10 Average:146.90 Median:153.00 stddev:16.06 (10.5%) stddev-sans-first:16.76 14:08:34 INFO - PROCESS | 1960 | Values: 155.0 158.0 151.0 136.0 135.0 138.0 119.0 144.0 177.0 156.0 14:08:34 INFO - PROCESS | 1960 | 14:08:34 INFO - PROCESS | 1960 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.90 Median:169.00 stddev:16.64 (9.8%) stddev-sans-first:17.65 14:08:34 INFO - PROCESS | 1960 | Values: 173.0 170.0 169.0 219.0 168.0 168.0 168.0 167.0 158.0 169.0 14:08:34 INFO - PROCESS | 1960 | 14:08:34 INFO - PROCESS | 1960 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.00 Median:73.50 stddev:13.14 (17.9%) stddev-sans-first:13.53 14:08:34 INFO - PROCESS | 1960 | Values: 69.0 68.0 70.0 70.0 75.0 98.0 98.0 94.0 72.0 66.0 14:08:34 INFO - PROCESS | 1960 | -------- Summary: end -------- 14:08:34 INFO - PROCESS | 1960 | 14:08:35 INFO - TEST-INFO | 1960: exit 0 14:08:35 INFO - TEST-OK | kraken | took 59017ms 14:08:35 INFO - SUITE-END | took 1174s 14:08:35 INFO - Completed test suite (00:19:34) 14:08:35 INFO - javascript benchmark 14:08:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3291.1772056985756, 3350.748693847962, 3377.341418423407, 3387.779794313371, 3420.606630237522, 5500.477454641813, 5607.214989901417, 5616.6630002199845, 5619.4942455179025, 5652.604317697757, 5618.325800903887, 5642.4357564243655, 5695.003374409483, 5714.25714657094, 5737.483626473617, 5103.795848166069, 5117.411497677777, 5145.871353216162, 5157.510036546522, 5244.947550524484, 5224.399194092671, 5238.4499627539135, 5258.973705131488, 5284.524392804647, 5304.92042619361, 5088.160453525201, 5100.132977393852, 5144.74276286188, 5196.038732834438, 5240.606954478391, 5137.6403651744395, 5172.0, 5175.171972484384, 5180.222966555009, 5200.271961925334, 5133.948660513384, 5161.870953226162, 5197.298364720752, 5197.402298735656, 5200.531952124327, 5422.75597598109, 5467.535259502947, 5590.18942253375, 5593.720313984284, 5616.606837521375, 6143.1092491588915, 6151.569390142692, 6158.476529494998, 6161.322254551996, 6176.96911515444, 6161.876762464725, 6221.4089661482085, 6224.439800417984, 6225.906611400833, 6227.252729672423, 5529.502344788948, 5663.915041274384, 5664.830055098354, 5670.206171136044, 5673.744681489343, 6262.812115636538, 6262.906056409158, 6265.248170219512, 6289.0252010938375, 6292.370762923671, 6043.8489037773525, 6052.394760524, 6057.515398768088, 6062.42406971337, 6075.787347442907, 6060.787872424444, 6061.3029501607, 6144.064619644798, 6210.4410603045035, 6223.408776166257, 5993.430624090704, 6039.033754599243, 6096.01269589369, 6134.386561343919, 6144.354842741537, 6166.691665416712, 6174.289956654952, 6179.134921111048, 6187.504999600022, 6190.876182476325, 4776.307435421914, 4811.4707382187935, 4848.539388758062, 4860.805567777249, 4905.969746353235, 4345.739255644671, 4349.021470169187, 4378.190034843564, 4422.447194100738, 4427.712198707074, 5585.664860108406, 5612.607117501736, 5615.157726341082, 5615.719214039282, 5627.212190293362, 6092.664903430265, 6170.691465426711, 6230.470410015199, 6243.282022567463, 6243.625382477066, 5558.666480011212, 5611.6071874969175, 5619.7471113799975, 5664.0937450007805, 5672.773089076473, 5298.70857102863, 5305.602079844027, 5305.965336759372, 5313.734313284398, 5315.654482458628, 6145.340757995227, 6181.5981961174175, 6262.405071518198, 6283.183186185766, 6294.055891616203, 6225.3774622537385, 6232.376762323913, 6250.999840025756, 6261.655608941552, 6264.906026409699, 6239.719212635351, 6276.435120839146, 6282.4974002080635, 6287.182666253358, 6308.558400911892, 5862.677552734641, 5949.940500594938, 5968.940310596839, 6150.323464418821, 6153.015517517176, 6102.792231004566, 6130.795626845359, 6167.105769663463, 6208.286047104641, 6209.59637623553, 6048.274207095177, 6053.727582258721, 6055.212822333068, 6109.526129009804, 6115.510759139169, 5996.2504686914135, 6003.339632640494, 6042.728077236623, 6056.757729690763, 6063.363346848694, 5728.742206600629, 5808.186853840551, 5834.358220595646, 5894.115882617642, 5916.0, 4975.7512124392915, 6064.302605200459, 6073.696315184311, 6078.322614818148, 6080.027195648675, 4939.851804445872, 5629.408912064175, 5878.764849405976, 5889.381614930542, 5904.881902362014], "value": 5632.757140484518, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [515.6055617452648, 523.4582207415326, 525.5060243371229, 537.2478530057919, 568.9857753556163, 28064.29839254017, 38042.80978595107, 38903.61096389035, 39174.0, 39181.02047448812, 42520.78739606305, 43343.34984975228, 43353.0, 43354.13291734163, 43356.349654755206, 8472.0, 9555.522223888813, 9806.558704858298, 9814.312998090136, 9837.065478779505, 8515.446495977772, 11156.219064665476, 11379.146564007691, 11389.373584452851, 11402.030827379682, 11378.260413073165, 11608.94195529021, 11621.070314374872, 11624.535018599247, 11626.360550169737, 8361.665533378658, 11173.72065698356, 11433.942830285796, 11521.135914806424, 11710.0, 11183.273087249303, 11381.544738210461, 11452.484638191301, 11460.140489463323, 11470.88529114715, 38192.236155277016, 38692.41961370582, 38730.80634596809, 38755.806220968996, 38768.80615596904, 3274.2960263543214, 3382.492626106079, 3771.8679846205528, 3827.7320587558884, 4038.172174704208, 29028.0, 29112.126636200737, 29393.0, 29765.958191463196, 29770.0, 2712.2541301142146, 2735.9863200684067, 2758.613794068832, 2793.2737488252997, 2817.4505971335598, 421039.0, 497395.0, 500120.0, 500538.0, 501470.0, 34064.65935340615, 59467.10799338014, 59887.401125989054, 59968.70015649981, 60010.69994650086, 35715.82142089273, 35780.0, 35850.0, 35855.0, 35867.92396228117, 11462.94268528652, 15032.62418439526, 15040.774388384183, 15040.924795376171, 15045.473408430653, 11567.363794991355, 11754.412279385997, 11755.177137600456, 11766.58816941403, 12779.808302875465, 7676.2707542784465, 7934.841303173905, 8091.433599648086, 8109.837803243902, 8116.553589552514, 3163.7469002479747, 3328.417526932777, 3376.814275214838, 3483.494893240466, 3501.529357669785, 953.8664586957832, 960.7742180587557, 970.3741086998988, 1015.5430056474615, 1021.3514418344256, 24116.63825042626, 29736.702632973826, 29760.255993600334, 29788.757252303192, 30355.696443035285, 1038.9220808439245, 1164.4759858063906, 1194.3729541990351, 1201.2672269915345, 1235.3946566182506, 14911.403543858341, 15106.773398399033, 15116.924415378073, 15132.940694151304, 15621.0, 655061.0, 658654.0, 658739.0, 662573.0, 663627.0, 59457.40542594519, 60202.69898650479, 60219.0, 60267.0, 60534.0, 1214.623466725318, 1251.974960500803, 1304.9543265985455, 1496.3266530061517, 1501.2568778454736, 28342.716572834008, 28825.711742882304, 29276.414471710872, 29411.411771764873, 29939.401211976074, 47737.56787296387, 47817.28274075822, 48213.276800847314, 48427.75786121047, 48705.07769533839, 424440.0, 426027.0, 426636.0, 427019.0, 427413.0, 22619.208327709108, 24919.626205607292, 25443.745562544136, 25693.614595780706, 26387.076452324076, 40691.593084069966, 40737.818403775345, 40950.0, 40991.0, 41442.0, 33989.49015764716, 34836.129096772784, 34938.65061349354, 36648.26703465969, 37657.55225910307, 33109.83445082759, 33909.66090339164, 36103.27793444169, 36567.26865462729, 37343.43984840175, 29601.407971840872, 35768.0, 36163.095922602144, 36255.64932208152, 36382.8180859094, 31849.522257165696, 32489.0, 32548.674513254566, 32619.673803261663, 32656.34687306193, 23765.762342376354, 23855.0, 24004.87997560001, 24057.157999470634, 24555.877220614497], "value": 18415.481418789448, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.3403248510017, 390.56256992168755, 393.9507561554806, 398.2890540385413, 400.74752905669425, 13583.796243056337, 20172.19311227549, 20918.89540552297, 20932.47668808279, 21021.0, 15777.0, 17571.0, 19766.901165494153, 20444.386668399933, 20847.166113355448, 5794.507466865311, 5986.191864098357, 6018.187544681477, 6027.819165425044, 6037.849053773669, 5616.410276920926, 5890.116482527612, 5915.615484993483, 5921.851953701171, 5957.523398128139, 5866.882662346772, 5871.324797648261, 5875.88248235035, 5882.3529411764675, 5887.88224235515, 5697.059985102474, 5827.155062515953, 5900.498457631106, 5908.615939963888, 5920.052791553331, 5110.412302585176, 5123.38519377677, 5151.5621172200035, 5152.175651895679, 5180.456052114511, 24246.878765606234, 24595.770211489355, 25899.0935317263, 25996.74003259981, 26005.349866253495, 2216.390492614528, 2233.530958498717, 2289.8053665438456, 2639.551276283017, 2713.1996061162004, 14604.977651564395, 14666.266686665624, 14742.336594853257, 14931.402743890232, 15461.5361539154, 1766.2934826069547, 1827.1412436154985, 1830.7711536057993, 1871.7192421136801, 1916.1856211110223, 117669.41165294118, 117931.41034294774, 124444.0, 125639.0, 127432.0, 10485.842712359321, 10989.560417583367, 11155.944220278847, 11195.992360687414, 11313.321200727984, 13944.117544131615, 14001.949853761009, 14034.157950523002, 14053.85946140546, 14062.226577538333, 6593.80218593443, 8128.715494957648, 8180.0, 8469.449485783527, 8602.569871506399, 8732.476051436934, 8753.868691969625, 8759.737207883774, 8790.296776257885, 8894.243989260985, 6453.645049522322, 6601.33986601336, 6610.570312929644, 6632.668366581652, 6703.162104736908, 1832.4136276391428, 1861.088066847262, 1885.4720678210124, 1886.1889387563479, 1892.687706528435, 923.1414784250711, 930.8184903943666, 974.3033730882453, 1056.5034433816095, 1061.6337363609543, 16657.250423730962, 20662.17351305961, 21203.15187392518, 21260.574788504142, 21285.36143915685, 865.7748985263751, 958.9712308630752, 985.6796541124163, 1006.0292510176391, 1039.3555995282973, 11011.559537618567, 11128.554857805597, 11306.378149201708, 11338.60314888975, 11599.478023488964, 555713.0, 570533.0, 577235.0, 596624.0, 601813.0, 120057.0, 120379.39810300892, 121429.0, 122634.38682806528, 125110.0, 965.6571916969515, 969.5297780576415, 1058.6241884131177, 1072.587053984206, 1076.4994277660926, 16302.532772050572, 16899.408520701716, 17450.9127454367, 17930.910345448712, 18146.81853181451, 48591.274798861195, 53313.20030199472, 54110.04201785169, 54525.45474545362, 54797.1780423302, 71916.64041679759, 79183.0, 80631.59684201777, 81601.0, 83523.5823820877, 7684.577348245676, 7846.411519136202, 7942.944209800155, 8025.15735847751, 8130.308923741311, 8917.051036674708, 11904.583339583072, 12117.454714537691, 12335.814962775745, 12386.62840114798, 10410.0, 10504.842427363748, 10550.671447164388, 10650.627228046982, 10732.946335268585, 10263.794724105626, 10364.170866330525, 10376.16990640762, 10615.0, 10679.786404272027, 10427.895721042996, 10568.524416401435, 10973.23187376876, 11036.668899933014, 11101.5559377624, 7886.290233878978, 7929.246721561442, 8030.959845200737, 8055.395845311742, 8268.0, 8632.352573557135, 8928.330375222014, 10477.633282835064, 10742.83885741699, 10797.514111864828], "value": 9623.99260271878, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1316.0655934286656, 1391.7773156294995, 1443.3216388297496, 1475.8155230596176, 1712.2551690014836, 7802.2587854153835, 8682.522461264627, 8879.289656827454, 8884.200421962023, 8885.555722213896, 8619.310455163588, 8825.117488251171, 8826.470411775297, 8827.029026807046, 8859.64561417544, 2141.15424407359, 2153.1495059451545, 2220.6891035255076, 2221.5445833604153, 2465.2234546118007, 2221.7333919929633, 2408.205292253552, 2498.600223964166, 2503.7245902950663, 2505.1357281737774, 2418.9395265118424, 2444.9022039118413, 2455.72986971433, 2460.0, 2460.237326428804, 2113.587850369179, 2118.834640947488, 2131.4138611881704, 2421.624648179535, 2451.252368027751, 2388.1283331584023, 2467.9136230231857, 2475.4553998120386, 2475.492524032569, 2480.1071614219045, 8568.400211985163, 8675.522846243517, 8686.218240358397, 8692.478451292942, 8720.651173953098, 4586.990862010352, 4699.295105734133, 5108.106081435734, 5420.810271640474, 5432.076546986982, 3464.428369319059, 3590.7666001710068, 3674.1365779041903, 3678.9264214715554, 3708.592054873962, 1024.1653052761976, 1025.8050970315692, 1036.9526777954245, 1048.3133547526381, 1048.5281623269484, 11702.590409335633, 11722.824157637642, 11730.35483048432, 11746.765064698744, 11751.353675547842, 4841.467438581753, 4854.417469903634, 4876.926846097312, 4923.359963204761, 5002.799888004439, 9992.300538962349, 10025.097741203326, 10040.79918401628, 10053.899461005443, 10092.545835437422, 5435.537979271806, 5474.178873169057, 5477.589180811455, 5486.862828429321, 5490.917636235459, 4842.5157484251995, 4887.975560122248, 4939.728314942641, 4976.054549635558, 4986.700797952134, 4014.337634290368, 4064.349704047397, 4129.29801933672, 4161.521425036099, 4195.1819395217635, 1356.9796453053211, 1359.429039803285, 1362.5845408229286, 1365.378752667534, 1375.917444953306, 851.7359618518155, 873.1312344217417, 902.5622573052065, 953.4994128082812, 962.6438217859297, 4482.5069242383315, 5928.525717942641, 5982.73077711504, 5992.8801423972145, 6173.41352571505, 864.8140649760331, 930.1303281431883, 946.3564775952281, 950.2398081534744, 1030.737162023694, 3577.94633080504, 4015.859444919472, 4089.795510224477, 4103.815328310233, 4104.692148088905, 10061.949690251502, 10445.947770261251, 10546.57813687444, 10550.947245263724, 10558.63044793444, 10408.271421000458, 10498.370097794157, 10521.053105220566, 10531.368117912949, 10537.578496860193, 7900.644470998819, 8127.593620319079, 8132.430729848972, 8136.755897323208, 8138.3896207784655, 5682.772689092555, 5937.910931335947, 6014.518838492998, 6073.544484163617, 6073.787417440369, 9639.421634701941, 9740.610375584898, 10007.049330313608, 10030.144423281716, 10038.949805250926, 8923.643054277756, 9878.110970012733, 10198.33710808795, 10363.844542332023, 10397.948010259901, 6577.210734711865, 7547.660355283915, 7591.316781489692, 7699.538027718355, 7706.730264440717, 8113.391495637969, 8245.13426090252, 8267.090620031911, 8281.171882811912, 8321.292690121407, 7412.851742965218, 7507.024086868891, 7531.435142364235, 7578.621068946641, 7610.010698609145, 7360.558366498028, 7373.668184931798, 7435.256474352522, 7487.026686530716, 7530.28462296081, 7010.404115650227, 7032.424147731411, 7057.611831349325, 7097.396721278749, 7106.0, 4858.392700912386, 4912.312276281252, 4927.531884470969, 4928.704277743347, 5023.572996295356, 4895.877603059809, 4897.877553061202, 4951.059298733229, 5065.56942659878, 5147.150720131136], "value": 4597.809951250234, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [269.50007735650365, 316.5252121817274, 317.54115303386607, 326.1894192930568, 330.68750031220475, 5137.409197942233, 5202.427732949377, 5218.817341393052, 5349.8127565535215, 5412.242286079952, 4731.33761273422, 5121.897562048757, 5192.71440070796, 5238.973805130979, 5253.474652534745, 3765.585785563586, 3878.0997311317465, 3930.941035884464, 3955.307821131305, 4012.8996775080564, 3309.420851351016, 3678.7240956928204, 4050.8784736457956, 4083.0, 4103.215101430887, 3154.5268209768487, 4024.8792536223814, 4027.174429242013, 4051.3315302974975, 4059.4925634295714, 3204.279037216631, 3817.7136714746366, 3880.556762506942, 4030.8109107813266, 4071.8982025449304, 3013.6835632258517, 3126.4059828632485, 3479.7564170508076, 3504.56192975878, 3530.082178633548, 4995.325631039799, 4998.225275082368, 5032.4967503249745, 5043.5460808527405, 5059.519345662156, 4233.470816147951, 4392.582704643054, 4403.955960440387, 4567.657425693086, 4579.335996280554, 4570.7029043112425, 5504.944950550484, 5515.531179849718, 5523.613347065707, 5527.198556209327, 3256.788308759923, 3813.809309534512, 3814.7901865397394, 3834.9041273968096, 3842.269968705937, 4865.8783530411665, 5601.500384074402, 5655.4061823508355, 5729.6562206267345, 5816.069428891357, 3342.8133012780113, 3774.188549461824, 3842.711796615209, 3849.7305188637088, 3915.8629447969183, 3319.535265062893, 3920.0, 3927.5286965564314, 3939.1333906540517, 3951.0, 4534.7052441591195, 4535.092981403733, 4538.432695913011, 4549.3176023596725, 4560.794764235551, 4656.3946686930485, 5202.5432878794, 5572.3870374258795, 5585.525230355465, 5587.776488940397, 1369.8424831017774, 1410.5204230561453, 1411.56947131126, 1428.0360756489329, 1431.8568143185807, 751.6692655231681, 754.9242329680184, 903.584351198443, 908.4322298563397, 921.9631214751469, 4795.760211989386, 5088.542031217208, 5161.612879034087, 5652.773889044474, 5709.885802283931, 5487.451254874481, 6067.635941843503, 6207.317195108534, 6228.283747369111, 6231.22109736283, 5451.618386712892, 5647.887042259132, 5659.0, 5712.943105525491, 5736.627119237236, 2750.3674154944074, 3002.7297543221216, 3256.2673398485153, 3259.885903993349, 3290.8848190313224, 6099.878002439927, 6511.804645860631, 6518.185226846645, 6524.641144737085, 6537.967310163419, 4807.6394270429855, 5736.971315143398, 6217.378262173836, 6367.61794292344, 6391.6258004529245, 5762.798302059492, 5791.681457519792, 5902.586818922634, 6080.8750381179625, 6477.740890364427, 5221.321228240457, 6054.878902421839, 6139.938600614116, 6218.53360997936, 6262.060690896402, 5068.645194836474, 6101.603395779438, 6159.568830182024, 6163.599366041193, 6188.319284878749, 5735.225744524436, 6006.768612434528, 6136.7852125175405, 6476.514261430506, 6488.351164883474, 3469.7918124912585, 3573.857045718142, 4065.7560546367313, 4122.866211791781, 4140.482439695038, 5451.264079349396, 6028.728707208098, 6235.937640623717, 6248.593841400295, 6251.187345645037, 4925.778339974638, 5784.652920824764, 6086.147939288415, 6097.176881120992, 6115.052166914135, 4960.313493412257, 5548.335499350098, 5605.523530499953, 5892.734826932712, 6103.481204097701, 4572.849517399093, 5202.800277829032, 5916.733746981482, 5928.733207005609, 5934.169216309634, 5165.586753059822, 5170.767315470737, 5208.5519805691265, 5641.210230567642, 5734.455226753452, 4657.627389808739, 5518.91721624184, 5536.390996990247, 5579.693116878449, 5590.189422533791], "value": 4170.7898195311, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.151863837948, 741.933226009659, 748.248010749197, 757.3297631596038, 794.5749024272016, 14645.853541464596, 14744.557663270092, 15094.47169349073, 15114.09315441073, 15123.546293611182, 14006.159630422182, 14064.367103480343, 14079.718405631882, 14082.014259001873, 14093.506727264548, 743.9330460258576, 769.4075561817398, 905.7192270396198, 941.6280569175154, 944.5135755086136, 942.8632848237002, 947.1901524196782, 947.8909925358603, 951.7763325618474, 952.5665822050985, 998.5805961496189, 1007.6120693532969, 1008.8234558952191, 1009.0716540782498, 1009.7425156585098, 760.5588758520045, 953.5470651440545, 985.5269470654055, 988.298308201178, 989.3173710140052, 740.9073865766779, 744.4714252880461, 744.8473063022068, 747.1818358896999, 748.1807420874134, 14752.631184220372, 14769.409223631043, 14793.40826366944, 14819.110853348726, 14828.0, 12286.13997020209, 12437.315947622878, 12547.058970577244, 12581.93709031449, 12799.0, 9949.154321882648, 9965.701028969142, 10129.797404051951, 10473.31923424975, 10509.369437833679, 3468.500654995834, 3483.076984599083, 3497.842597083137, 3553.0, 3553.822308884571, 83729.8135093249, 83903.58048209781, 84784.0, 85156.57421712852, 86044.0, 9424.0, 9555.569999350046, 9597.23222142227, 9616.374935629161, 9701.223902087817, 22830.543389132123, 22834.42913927165, 22839.429014274778, 22852.885735571548, 22855.428614284443, 7347.669354879044, 7405.1334864928285, 7448.851022979618, 7458.0, 7593.772186834404, 4029.222054793844, 4048.149888523384, 4055.270051390778, 4075.857344992911, 4372.453443319585, 3509.596396414394, 3635.8727444539313, 3639.1629925117372, 3642.7267954903486, 3652.4521321801412, 598.5510866849862, 677.833930686975, 678.6538865178794, 717.2863001313726, 725.1443296909591, 514.354485121178, 550.6585916731572, 566.5071387892477, 601.9036954087412, 607.1378642327905, 8264.21489958453, 8287.0, 8447.788805279793, 8488.490690558463, 8547.829043419097, 485.4490153675604, 552.3923683947705, 591.721890711365, 597.6294697287623, 641.0448431836624, 3824.158685089276, 3850.4609354690365, 3873.283442563135, 3889.2610404023144, 3935.8622448214746, 96543.0345696548, 96802.0, 97961.51019245, 101878.98121018843, 103162.48418757858, 60685.393146068855, 75534.11164720925, 78025.60987195028, 80506.5974670123, 81389.59305203435, 75196.62401688065, 75301.24698753053, 75466.24533754592, 75784.0, 76530.23469765342, 7554.2823431773895, 7561.546307221584, 7630.236976302325, 7870.29167374939, 8001.119876813662, 47029.294560582304, 47577.286340704224, 47651.76174119107, 48971.0, 49015.0, 64252.35747642651, 69119.30880691129, 69622.30377696158, 69643.25891852744, 70883.0, 10158.695239142837, 10463.267571270242, 10554.525046372779, 10565.894341056799, 10920.563177472812, 10953.34279943174, 10979.0, 11093.445327733742, 11528.481218345363, 12096.818547721967, 10237.232207584311, 10403.687889363331, 10730.034296913314, 10735.194860385345, 10741.731456713645, 10439.58241670355, 10656.467176641292, 10678.57285708563, 10892.237543371888, 10932.289401188897, 10346.430946298025, 10491.213159012952, 10566.207534434794, 10625.36247825102, 10712.57149714034, 8836.160564746338, 8908.198262156437, 9113.589888455168, 9218.21645160169, 9492.03403549644, 8747.65009399617, 8830.205281524693, 8891.110888911056, 9022.503762292874, 9108.089191080839], "value": 6891.313085152307, "unit": "score"}], "name": "dromaeo_css", "value": 7135.024692004967, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 109.0, 112.0, 113.0, 113.0, 113.0, 112.0, 104.0, 113.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 123.0, 145.0, 169.0, 120.0, 141.0, 140.0, 120.0, 171.0, 118.0], "value": 141.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 189.0, 184.0, 185.0, 186.0, 186.0, 185.0, 188.0, 185.0, 186.0], "value": 186.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 76.0, 90.0, 95.0, 65.0, 83.0, 84.0, 110.0, 86.0, 55.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 138.0, 78.0, 80.0, 79.0, 125.0, 81.0, 77.0, 127.0], "value": 97.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 86.0, 87.0, 85.0, 89.0, 85.0, 89.0, 84.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 93.0, 94.0, 93.0, 93.0, 93.0, 94.0, 93.0, 106.0, 149.0], "value": 100.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 84.0, 85.0, 101.0, 111.0, 115.0, 137.0, 134.0, 87.0], "value": 102.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 58.0, 58.0, 58.0, 60.0, 61.0, 61.0, 80.0, 59.0, 57.0], "value": 61.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 81.0, 74.0, 70.0, 69.0, 70.0, 69.0, 69.0, 69.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 81.0, 81.0, 82.0, 81.0, 81.0, 82.0, 84.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 158.0, 151.0, 136.0, 135.0, 138.0, 119.0, 144.0, 177.0, 156.0], "value": 146.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 170.0, 169.0, 219.0, 168.0, 168.0, 168.0, 167.0, 158.0, 169.0], "value": 172.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 70.0, 70.0, 75.0, 98.0, 98.0, 94.0, 72.0, 66.0], "value": 78.0, "unit": "score"}], "name": "kraken", "value": 1518.9, "alertThreshold": 2.0}]} 14:08:35 INFO - Return code: 0 14:08:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:08:35 ERROR - # TBPL SUCCESS # 14:08:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:08:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:08:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:08:36 INFO - 2016-04-29 14:08:36.014 screenresolution[1964:20565] starting screenresolution argv=screenresolution get 14:08:36 INFO - 2016-04-29 14:08:36.016 screenresolution[1964:20565] Display 0: 1600x1200x32@60 14:08:36 INFO - 2016-04-29 14:08:36.021 screenresolution[1965:20568] starting screenresolution argv=screenresolution list 14:08:36 INFO - Available Modes on Display 0 14:08:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:08:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:08:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:08:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:08:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:08:36 INFO - Intel Iris: 14:08:36 INFO - Chipset Model: Intel Iris 14:08:36 INFO - Type: GPU 14:08:36 INFO - Bus: Built-In 14:08:36 INFO - VRAM (Dynamic, Max): 1536 MB 14:08:36 INFO - Vendor: Intel (0x8086) 14:08:36 INFO - Device ID: 0x0a2e 14:08:36 INFO - Revision ID: 0x0009 14:08:36 INFO - Displays: 14:08:36 INFO - Display: 14:08:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:08:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:08:36 INFO - Display Serial Number: 1600x1200 60 14:08:36 INFO - Main Display: Yes 14:08:36 INFO - Mirror: Off 14:08:36 INFO - Online: Yes 14:08:36 INFO - Rotation: Supported 14:08:36 INFO - Return code: 0 14:08:36 INFO - Running post-action listener: _resource_record_post_action 14:08:36 INFO - Running post-run listener: _resource_record_post_run 14:08:37 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 1208320; Write bytes: 694630912; Read time: 9; Write time: 1878 14:08:37 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 376721920; Write bytes: 370710016; Read time: 28110; Write time: 671 14:08:37 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 1208320; Write bytes: 294796288; Read time: 9; Write time: 1093 14:08:37 INFO - Running post-run listener: _upload_blobber_files 14:08:37 INFO - Blob upload gear active. 14:08:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:08:37 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 14:08:37 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'] 14:08:37 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 14:08:37 INFO - (blobuploader) - INFO - Open directory for files ... 14:08:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:08:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:08:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:08:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:08:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:08:38 INFO - (blobuploader) - INFO - Done attempting. 14:08:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:08:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:08:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:08:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:08:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:08:39 INFO - (blobuploader) - INFO - Done attempting. 14:08:39 INFO - (blobuploader) - INFO - Iteration through files over. 14:08:39 INFO - Return code: 0 14:08:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:08:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:08:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d8347a8157297483d76177c6b41bf44c7fc6a7daeb6b94b766c52f0d9785a1459f580c0d35e0accb87dd8b0c524ed211bf40a4e73975f3def3097cf4dab5b9f8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eb67d2a2991a4c60ad40d6c81da7b179c72071b4c99f8a9530914f731e5e19c211d9600dbb77096c8d5404eb700f94a1c19792c3dd07d3d49f79cc808c27aa48"} 14:08:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:08:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:08:39 INFO - Contents: 14:08:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d8347a8157297483d76177c6b41bf44c7fc6a7daeb6b94b766c52f0d9785a1459f580c0d35e0accb87dd8b0c524ed211bf40a4e73975f3def3097cf4dab5b9f8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eb67d2a2991a4c60ad40d6c81da7b179c72071b4c99f8a9530914f731e5e19c211d9600dbb77096c8d5404eb700f94a1c19792c3dd07d3d49f79cc808c27aa48"} 14:08:39 INFO - Running post-run listener: copy_logs_to_upload_dir 14:08:39 INFO - Copying logs to upload dir... 14:08:39 INFO - mkdir: /builds/slave/test/build/upload/logs 14:08:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.162122 ========= master_lag: 5.29 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-04-29 14:08:45.162507) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 12 secs) (at 2016-04-29 14:08:45.166904) ========= 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.x3hjBBDkgU/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.A8aWbgIiAW/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d8347a8157297483d76177c6b41bf44c7fc6a7daeb6b94b766c52f0d9785a1459f580c0d35e0accb87dd8b0c524ed211bf40a4e73975f3def3097cf4dab5b9f8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eb67d2a2991a4c60ad40d6c81da7b179c72071b4c99f8a9530914f731e5e19c211d9600dbb77096c8d5404eb700f94a1c19792c3dd07d3d49f79cc808c27aa48"} build_url:https://queue.taskcluster.net/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011226 build_url: 'https://queue.taskcluster.net/v1/task/Jq_hDOFlRtCdBMCPIL4GeQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d8347a8157297483d76177c6b41bf44c7fc6a7daeb6b94b766c52f0d9785a1459f580c0d35e0accb87dd8b0c524ed211bf40a4e73975f3def3097cf4dab5b9f8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/eb67d2a2991a4c60ad40d6c81da7b179c72071b4c99f8a9530914f731e5e19c211d9600dbb77096c8d5404eb700f94a1c19792c3dd07d3d49f79cc808c27aa48"}' ========= master_lag: 12.68 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 12 secs) (at 2016-04-29 14:08:57.859064) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 24 secs) (at 2016-04-29 14:08:57.859356) ========= 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.x3hjBBDkgU/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.A8aWbgIiAW/Listeners TMPDIR=/var/folders/0j/h0q2j0xx3k5cx8qlz357zmjr00000w/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.005451 ========= master_lag: 24.41 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 24 secs) (at 2016-04-29 14:09:22.276463) ========= ========= Started reboot skipped (results: 3, elapsed: 3 secs) (at 2016-04-29 14:09:22.276871) ========= ========= Finished reboot skipped (results: 3, elapsed: 3 secs) (at 2016-04-29 14:09:26.273492) ========= ========= Total master_lag: 44.97 =========