builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0066 starttime: 1463657357.31 results: success (0) buildid: 20160519032344 builduid: 0b3301f43abe458eb5cd4ca48e10e799 revision: 029dcd1503478771cb4a6795a6e8f1fec802c0fd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 04:29:17.307994) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 04:29:17.308524) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 04:29:17.308846) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 04:29:17.329541) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 04:29:17.329878) ========= 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.vPBq9aPgrB/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.LGJX3t6brS/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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-05-19 04:29:17-- 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% 161M=0s 2016-05-19 04:29:17 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.102257 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 04:29:17.450921) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 04:29:17.451286) ========= 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.vPBq9aPgrB/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.LGJX3t6brS/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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.026546 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 04:29:17.500386) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 04:29:17.500795) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 029dcd1503478771cb4a6795a6e8f1fec802c0fd --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 029dcd1503478771cb4a6795a6e8f1fec802c0fd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vPBq9aPgrB/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.LGJX3t6brS/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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-05-19 04:29:17,570 truncating revision to first 12 chars 2016-05-19 04:29:17,570 Setting DEBUG logging. 2016-05-19 04:29:17,570 attempt 1/10 2016-05-19 04:29:17,570 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/029dcd150347?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 04:29:18,233 unpacking tar archive at: fx-team-029dcd150347/testing/mozharness/ program finished with exit code 0 elapsedTime=0.912588 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 04:29:18.431065) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 04:29:18.431390) ========= script_repo_revision: 029dcd1503478771cb4a6795a6e8f1fec802c0fd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 04:29:18.431826) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 04:29:18.432098) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 04:29:18.446079) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-19 04:29:18.446387) ========= /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.vPBq9aPgrB/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.LGJX3t6brS/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 04:29:18 INFO - MultiFileLogger online at 20160519 04:29:18 in /builds/slave/test 04:29:18 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 04:29:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:29:18 INFO - {'append_to_log': False, 04:29:18 INFO - 'base_work_dir': '/builds/slave/test', 04:29:18 INFO - 'blob_upload_branch': 'Fx-Team', 04:29:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:29:18 INFO - 'branch': 'Fx-Team', 04:29:18 INFO - 'buildbot_json_path': 'buildprops.json', 04:29:18 INFO - 'config_files': ('talos/mac_config.py',), 04:29:18 INFO - 'default_actions': ('clobber', 04:29:18 INFO - 'read-buildbot-config', 04:29:18 INFO - 'download-and-extract', 04:29:18 INFO - 'populate-webroot', 04:29:18 INFO - 'create-virtualenv', 04:29:18 INFO - 'install', 04:29:18 INFO - 'run-tests'), 04:29:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:29:18 INFO - 'download_minidump_stackwalk': True, 04:29:18 INFO - 'download_symbols': 'ondemand', 04:29:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:29:18 INFO - 'tooltool.py': '/tools/tooltool.py', 04:29:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:29:18 INFO - '/tools/misc-python/virtualenv.py')}, 04:29:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:29:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:29:18 INFO - 'installer_path': 'installer.exe', 04:29:18 INFO - 'log_level': 'info', 04:29:18 INFO - 'log_name': 'talos', 04:29:18 INFO - 'log_to_console': True, 04:29:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:29:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:29:18 INFO - 'opt_config_files': (), 04:29:18 INFO - 'pip_index': False, 04:29:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:29:18 INFO - 'cmd': ('bash', 04:29:18 INFO - '-c', 04:29:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:29:18 INFO - 'enabled': True, 04:29:18 INFO - 'halt_on_failure': False, 04:29:18 INFO - 'name': 'check_screen_resolution'},), 04:29:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:29:18 INFO - 'cmd': ('bash', 04:29:18 INFO - '-c', 04:29:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:29:18 INFO - 'enabled': True, 04:29:18 INFO - 'halt_on_failure': False, 04:29:18 INFO - 'name': 'check_screen_resolution'},), 04:29:18 INFO - 'run_cmd_checks_enabled': True, 04:29:18 INFO - 'sps_profile': False, 04:29:18 INFO - 'sps_profile_interval': 0, 04:29:18 INFO - 'suite': 'dromaeojs', 04:29:18 INFO - 'system_bits': '32', 04:29:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:29:18 INFO - 'title': 't-yosemite-r7-0066', 04:29:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:29:18 INFO - 'use_talos_json': True, 04:29:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:29:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:29:18 INFO - 'work_dir': 'build'} 04:29:18 INFO - ##### 04:29:18 INFO - ##### Running clobber step. 04:29:18 INFO - ##### 04:29:18 INFO - Running pre-action listener: _resource_record_pre_action 04:29:18 INFO - Running main action method: clobber 04:29:18 INFO - rmtree: /builds/slave/test/build 04:29:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:29:20 INFO - Running post-action listener: _resource_record_post_action 04:29:20 INFO - ##### 04:29:20 INFO - ##### Finished clobber step (success) 04:29:20 INFO - ##### 04:29:20 INFO - ##### 04:29:20 INFO - ##### Running read-buildbot-config step. 04:29:20 INFO - ##### 04:29:20 INFO - Running pre-action listener: _resource_record_pre_action 04:29:20 INFO - Running main action method: read_buildbot_config 04:29:20 INFO - Using buildbot properties: 04:29:20 INFO - { 04:29:20 INFO - "project": "", 04:29:20 INFO - "product": "firefox", 04:29:20 INFO - "script_repo_revision": "production", 04:29:20 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 04:29:20 INFO - "repository": "", 04:29:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 04:29:20 INFO - "buildid": "20160519032344", 04:29:20 INFO - "pgo_build": "False", 04:29:20 INFO - "basedir": "/builds/slave/test", 04:29:20 INFO - "buildnumber": 777, 04:29:20 INFO - "slavename": "t-yosemite-r7-0066", 04:29:20 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:29:20 INFO - "platform": "yosemite_r7", 04:29:20 INFO - "scheduler": "tests-fx-team-macosx64-talos", 04:29:20 INFO - "branch": "fx-team", 04:29:20 INFO - "repo_path": "integration/fx-team", 04:29:20 INFO - "revision": "029dcd1503478771cb4a6795a6e8f1fec802c0fd", 04:29:20 INFO - "stage_platform": "macosx64", 04:29:20 INFO - "builduid": "0b3301f43abe458eb5cd4ca48e10e799", 04:29:20 INFO - "slavebuilddir": "test" 04:29:20 INFO - } 04:29:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 04:29:20 INFO - Running post-action listener: _resource_record_post_action 04:29:20 INFO - ##### 04:29:20 INFO - ##### Finished read-buildbot-config step (success) 04:29:20 INFO - ##### 04:29:20 INFO - ##### 04:29:20 INFO - ##### Running download-and-extract step. 04:29:20 INFO - ##### 04:29:20 INFO - Running pre-action listener: _resource_record_pre_action 04:29:20 INFO - Running main action method: download_and_extract 04:29:20 INFO - mkdir: /builds/slave/test/build/tests 04:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:20 INFO - https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:29:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:29:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 04:29:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/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 04:29:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/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 04:29:21 INFO - Downloaded 1199 bytes. 04:29:21 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 04:29:21 INFO - Using the following test package requirements: 04:29:21 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:29:21 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:29:21 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 04:29:21 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:29:21 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 04:29:21 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:29:21 INFO - u'jsshell-mac.zip'], 04:29:21 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:29:21 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 04:29:21 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 04:29:21 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:29:21 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 04:29:21 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:29:21 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 04:29:21 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:29:21 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 04:29:21 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 04:29:21 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 04:29:21 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:29: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')]} 04:29:21 INFO - https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:29:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:29:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:29:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/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 04:29:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/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 04:29:22 INFO - Downloaded 18628983 bytes. 04:29:22 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'] 04:29:22 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 04:29:26 INFO - Return code: 0 04:29:26 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 04:29:26 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')]} 04:29:26 INFO - https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:29:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:29:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 04:29:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/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 04:29:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/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 04:29:27 INFO - Downloaded 18628983 bytes. 04:29:27 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'] 04:29:27 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 04:29:30 INFO - Return code: 0 04:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:29:30 INFO - https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:29:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:29:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 04:29:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/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 04:29:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/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 04:29:31 INFO - Downloaded 10840194 bytes. 04:29:31 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'] 04:29:31 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 04:29:32 INFO - Return code: 0 04:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:32 INFO - https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:29:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:29:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:29:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 04:29:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/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 04:29:35 INFO - Downloaded 110308328 bytes. 04:29:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:29:35 INFO - mkdir: /builds/slave/test/properties 04:29:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:29:35 INFO - Writing to file /builds/slave/test/properties/build_url 04:29:35 INFO - Contents: 04:29:35 INFO - build_url:https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 04:29:36 INFO - Running post-action listener: _resource_record_post_action 04:29:36 INFO - Running post-action listener: set_extra_try_arguments 04:29:36 INFO - ##### 04:29:36 INFO - ##### Finished download-and-extract step (success) 04:29:36 INFO - ##### 04:29:36 INFO - ##### 04:29:36 INFO - ##### Running populate-webroot step. 04:29:36 INFO - ##### 04:29:36 INFO - Running pre-action listener: _resource_record_pre_action 04:29:36 INFO - Running main action method: populate_webroot 04:29:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:29:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:29:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:29:36 INFO - '--tppagecycles', 04:29:36 INFO - '7'], 04:29:36 INFO - 'tests': ['tsvgm']}}, 04:29:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:29:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:29:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:29:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:29:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:29:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:29:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:36 INFO - 'talos_options': ['--disable-e10s'], 04:29:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:29:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:29:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:36 INFO - 'talos_options': ['--disable-e10s'], 04:29:36 INFO - 'tests': ['damp', 'tps']}, 04:29:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:36 INFO - 'tests': ['damp', 'tps']}, 04:29:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:29:36 INFO - 'tests': ['dromaeo_dom']}, 04:29:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:29:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:29:36 INFO - 'tests': ['a11yr', 04:29:36 INFO - 'ts_paint', 04:29:36 INFO - 'tpaint', 04:29:36 INFO - 'sessionrestore', 04:29:36 INFO - 'sessionrestore_no_auto_restore', 04:29:36 INFO - 'tabpaint']}, 04:29:36 INFO - 'other-e10s': {'tests': ['a11yr', 04:29:36 INFO - 'ts_paint', 04:29:36 INFO - 'tpaint', 04:29:36 INFO - 'sessionrestore', 04:29:36 INFO - 'sessionrestore_no_auto_restore', 04:29:36 INFO - 'tabpaint']}, 04:29:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:29:36 INFO - 'ts_paint', 04:29:36 INFO - 'tpaint', 04:29:36 INFO - 'sessionrestore', 04:29:36 INFO - 'sessionrestore_no_auto_restore', 04:29:36 INFO - 'tabpaint']}, 04:29:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:29:36 INFO - 'ts_paint', 04:29:36 INFO - 'tpaint', 04:29:36 INFO - 'sessionrestore', 04:29:36 INFO - 'sessionrestore_no_auto_restore', 04:29:36 INFO - 'tabpaint']}, 04:29:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:29:36 INFO - 'tests': ['a11yr', 04:29:36 INFO - 'ts_paint', 04:29:36 INFO - 'tpaint', 04:29:36 INFO - 'sessionrestore', 04:29:36 INFO - 'sessionrestore_no_auto_restore', 04:29:36 INFO - 'tabpaint']}, 04:29:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:29:36 INFO - 'tests': ['a11yr', 04:29:36 INFO - 'ts_paint', 04:29:36 INFO - 'tpaint', 04:29:36 INFO - 'sessionrestore', 04:29:36 INFO - 'sessionrestore_no_auto_restore', 04:29:36 INFO - 'tabpaint']}, 04:29:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:29:36 INFO - 'tests': ['tsvgx', 04:29:36 INFO - 'tsvgr_opacity', 04:29:36 INFO - 'tart', 04:29:36 INFO - 'tscrollx', 04:29:36 INFO - 'cart']}, 04:29:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:29:36 INFO - 'tsvgr_opacity', 04:29:36 INFO - 'tart', 04:29:36 INFO - 'tscrollx', 04:29:36 INFO - 'cart']}, 04:29:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:36 INFO - 'talos_options': ['--disable-e10s'], 04:29:36 INFO - 'tests': ['tp5o']}, 04:29:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:36 INFO - 'tests': ['tp5o']}, 04:29:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:36 INFO - 'talos_options': ['--disable-e10s', 04:29:36 INFO - '--xperf_path', 04:29:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:29:36 INFO - 'tests': ['tp5n']}, 04:29:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:29:36 INFO - 'talos_options': ['--xperf_path', 04:29:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:29:36 INFO - 'tests': ['tp5n']}}, 04:29:36 INFO - 'talos.zip': {'path': '', 04:29:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:29:36 INFO - Running post-action listener: _resource_record_post_action 04:29:36 INFO - ##### 04:29:36 INFO - ##### Finished populate-webroot step (success) 04:29:36 INFO - ##### 04:29:36 INFO - ##### 04:29:36 INFO - ##### Running create-virtualenv step. 04:29:36 INFO - ##### 04:29:36 INFO - Running pre-action listener: _resource_record_pre_action 04:29:36 INFO - Running main action method: create_virtualenv 04:29:36 INFO - Creating virtualenv /builds/slave/test/build/venv 04:29:36 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 04:29:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:29:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:29:36 INFO - Using real prefix '/tools/python27' 04:29:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:29:37 INFO - Installing distribute.............................................................................................................................................................................................done. 04:29:40 INFO - Installing pip.................done. 04:29:40 INFO - Return code: 0 04:29:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:29:40 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')]} 04:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:40 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')]} 04:29:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:40 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 0x106513a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106519570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1594b6640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b7d30>, '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 0x1061dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063aeb20>, '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.vPBq9aPgrB/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.LGJX3t6brS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 04:29:40 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 04:29:40 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 04:29:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vPBq9aPgrB/Render', 04:29:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:40 INFO - 'HOME': '/Users/cltbld', 04:29:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:40 INFO - 'LOGNAME': 'cltbld', 04:29:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:40 INFO - 'MOZ_NO_REMOTE': '1', 04:29:40 INFO - 'NO_EM_RESTART': '1', 04:29:40 INFO - 'PAGER': '/bin/cat', 04:29:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:40 INFO - 'PWD': '/builds/slave/test', 04:29:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:40 INFO - 'SHELL': '/bin/bash', 04:29:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LGJX3t6brS/Listeners', 04:29:40 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 04:29:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:40 INFO - 'USER': 'cltbld', 04:29:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:40 INFO - 'XPC_FLAGS': '0x0', 04:29:40 INFO - 'XPC_SERVICE_NAME': '0', 04:29:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:40 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 04:29:40 INFO - Cleaning up... 04:29:40 INFO - Return code: 0 04:29:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:29:40 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')]} 04:29:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:40 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')]} 04:29:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106513a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106519570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1594b6640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b7d30>, '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 0x1061dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063aeb20>, '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.vPBq9aPgrB/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.LGJX3t6brS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 04:29:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:29:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:29:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vPBq9aPgrB/Render', 04:29:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:40 INFO - 'HOME': '/Users/cltbld', 04:29:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:40 INFO - 'LOGNAME': 'cltbld', 04:29:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:40 INFO - 'MOZ_NO_REMOTE': '1', 04:29:40 INFO - 'NO_EM_RESTART': '1', 04:29:40 INFO - 'PAGER': '/bin/cat', 04:29:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:40 INFO - 'PWD': '/builds/slave/test', 04:29:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:40 INFO - 'SHELL': '/bin/bash', 04:29:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LGJX3t6brS/Listeners', 04:29:40 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 04:29:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:40 INFO - 'USER': 'cltbld', 04:29:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:40 INFO - 'XPC_FLAGS': '0x0', 04:29:40 INFO - 'XPC_SERVICE_NAME': '0', 04:29:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:40 INFO - Downloading/unpacking psutil>=3.1.1 04:29:40 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 04:29:40 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 04:29:40 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 04:29:40 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 04:29:40 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 04:29:40 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 04:29:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:29:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:29:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:29:43 INFO - Installing collected packages: psutil 04:29:43 INFO - Running setup.py install for psutil 04:29:43 INFO - building 'psutil._psutil_osx' extension 04:29:43 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 04:29:44 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 04:29:44 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 04:29:44 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 04:29:44 INFO - building 'psutil._psutil_posix' extension 04:29:44 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 04:29:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:29:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:29:44 INFO - ^ 04:29:44 INFO - 1 warning generated. 04:29:44 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 04:29:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:29:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:29:45 INFO - Successfully installed psutil 04:29:45 INFO - Cleaning up... 04:29:45 INFO - Return code: 0 04:29:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:29:45 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')]} 04:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:45 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')]} 04:29:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106513a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106519570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1594b6640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b7d30>, '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 0x1061dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063aeb20>, '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.vPBq9aPgrB/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.LGJX3t6brS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 04:29:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:29:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vPBq9aPgrB/Render', 04:29:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:45 INFO - 'HOME': '/Users/cltbld', 04:29:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:45 INFO - 'LOGNAME': 'cltbld', 04:29:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:45 INFO - 'MOZ_NO_REMOTE': '1', 04:29:45 INFO - 'NO_EM_RESTART': '1', 04:29:45 INFO - 'PAGER': '/bin/cat', 04:29:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:45 INFO - 'PWD': '/builds/slave/test', 04:29:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:45 INFO - 'SHELL': '/bin/bash', 04:29:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LGJX3t6brS/Listeners', 04:29:45 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 04:29:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:45 INFO - 'USER': 'cltbld', 04:29:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:45 INFO - 'XPC_FLAGS': '0x0', 04:29:45 INFO - 'XPC_SERVICE_NAME': '0', 04:29:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:29:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:29:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:29:47 INFO - Installing collected packages: mozsystemmonitor 04:29:47 INFO - Running setup.py install for mozsystemmonitor 04:29:48 INFO - Successfully installed mozsystemmonitor 04:29:48 INFO - Cleaning up... 04:29:48 INFO - Return code: 0 04:29:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:29:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:48 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 0x106513a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106519570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1594b6640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b7d30>, '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 0x1061dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063aeb20>, '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.vPBq9aPgrB/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.LGJX3t6brS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 04:29:48 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 04:29:48 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 04:29:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vPBq9aPgrB/Render', 04:29:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:48 INFO - 'HOME': '/Users/cltbld', 04:29:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:48 INFO - 'LOGNAME': 'cltbld', 04:29:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:48 INFO - 'MOZ_NO_REMOTE': '1', 04:29:48 INFO - 'NO_EM_RESTART': '1', 04:29:48 INFO - 'PAGER': '/bin/cat', 04:29:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:48 INFO - 'PWD': '/builds/slave/test', 04:29:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:48 INFO - 'SHELL': '/bin/bash', 04:29:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LGJX3t6brS/Listeners', 04:29:48 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 04:29:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:48 INFO - 'USER': 'cltbld', 04:29:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:48 INFO - 'XPC_FLAGS': '0x0', 04:29:48 INFO - 'XPC_SERVICE_NAME': '0', 04:29:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:48 INFO - Downloading/unpacking blobuploader==1.2.4 04:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:50 INFO - Downloading blobuploader-1.2.4.tar.gz 04:29:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:29:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:29:50 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 04:29:50 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 04:29:50 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 04:29:50 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 04:29:50 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 04:29:50 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 04:29:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:29:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:29:51 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 04:29:51 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 04:29:51 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 04:29:51 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 04:29:51 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 04:29:51 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 04:29:52 INFO - Downloading docopt-0.6.1.tar.gz 04:29:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:29:52 INFO - Installing collected packages: blobuploader, requests, docopt 04:29:52 INFO - Running setup.py install for blobuploader 04:29:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:29:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:29:52 INFO - Running setup.py install for requests 04:29:52 INFO - Running setup.py install for docopt 04:29:52 INFO - Successfully installed blobuploader requests docopt 04:29:52 INFO - Cleaning up... 04:29:53 INFO - Return code: 0 04:29:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:53 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 0x106513a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106519570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1594b6640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b7d30>, '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 0x1061dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063aeb20>, '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.vPBq9aPgrB/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.LGJX3t6brS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 04:29:53 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 04:29:53 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 04:29:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vPBq9aPgrB/Render', 04:29:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:53 INFO - 'HOME': '/Users/cltbld', 04:29:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:53 INFO - 'LOGNAME': 'cltbld', 04:29:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:53 INFO - 'MOZ_NO_REMOTE': '1', 04:29:53 INFO - 'NO_EM_RESTART': '1', 04:29:53 INFO - 'PAGER': '/bin/cat', 04:29:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:53 INFO - 'PWD': '/builds/slave/test', 04:29:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:53 INFO - 'SHELL': '/bin/bash', 04:29:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LGJX3t6brS/Listeners', 04:29:53 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 04:29:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:53 INFO - 'USER': 'cltbld', 04:29:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:53 INFO - 'XPC_FLAGS': '0x0', 04:29:53 INFO - 'XPC_SERVICE_NAME': '0', 04:29:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:29:53 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-GPFDns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:29:53 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-CDWjFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:29:53 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-BQDord-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:29:53 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-IVrxSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:29:53 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-w7GdfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:29:53 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-mp4BtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:29:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:29:53 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-15zvz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:29:54 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-u2mnDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:29:54 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-362MDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:29:54 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-Z1M8CC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:29:54 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-iQOfpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:29:54 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-djuywD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:29:54 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-P5RoZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:29:54 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-iskF_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:54 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-Rkl5p0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:29:54 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-gg4YpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:29:54 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-JWFlyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:29:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:29:55 INFO - Running setup.py install for manifestparser 04:29:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:29:55 INFO - Running setup.py install for mozcrash 04:29:55 INFO - Running setup.py install for mozdebug 04:29:55 INFO - Running setup.py install for mozdevice 04:29:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:29:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:29:55 INFO - Running setup.py install for mozfile 04:29:55 INFO - Running setup.py install for mozhttpd 04:29:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:29:55 INFO - Running setup.py install for mozinfo 04:29:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:29:56 INFO - Running setup.py install for mozInstall 04:29:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:29:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:29:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:29:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:29:56 INFO - Running setup.py install for mozleak 04:29:56 INFO - Running setup.py install for mozlog 04:29:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:29:56 INFO - Running setup.py install for moznetwork 04:29:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:29:56 INFO - Running setup.py install for mozprocess 04:29:56 INFO - Running setup.py install for mozprofile 04:29:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:29:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:29:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:29:57 INFO - Running setup.py install for mozrunner 04:29:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:29:57 INFO - Running setup.py install for mozscreenshot 04:29:57 INFO - Running setup.py install for moztest 04:29:57 INFO - Running setup.py install for mozversion 04:29:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:29:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:29:57 INFO - Cleaning up... 04:29:57 INFO - Return code: 0 04:29:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:29:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:29:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:29:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:29:57 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 0x106513a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106519570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1594b6640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b7d30>, '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 0x1061dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063aeb20>, '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.vPBq9aPgrB/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.LGJX3t6brS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 04:29:57 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 04:29:57 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 04:29:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vPBq9aPgrB/Render', 04:29:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:29:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:29:57 INFO - 'HOME': '/Users/cltbld', 04:29:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:29:57 INFO - 'LOGNAME': 'cltbld', 04:29:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:29:57 INFO - 'MOZ_NO_REMOTE': '1', 04:29:57 INFO - 'NO_EM_RESTART': '1', 04:29:57 INFO - 'PAGER': '/bin/cat', 04:29:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:29:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:29:57 INFO - 'PWD': '/builds/slave/test', 04:29:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:29:57 INFO - 'SHELL': '/bin/bash', 04:29:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LGJX3t6brS/Listeners', 04:29:57 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 04:29:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:29:57 INFO - 'USER': 'cltbld', 04:29:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:29:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:29:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:29:57 INFO - 'XPC_FLAGS': '0x0', 04:29:57 INFO - 'XPC_SERVICE_NAME': '0', 04:29:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:29:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:29:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:29:57 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-9yHcEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:29:58 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)) 04:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:29:58 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-MMCmM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:29:58 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)) 04:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:29:58 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-yGqd8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:29:58 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)) 04:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:29:58 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-H54cKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:29:58 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)) 04:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:29:58 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-nYnMfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:29:58 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)) 04:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:29:58 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-7J3piS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:29:58 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)) 04:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:29:58 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-a5HaP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:29:58 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)) 04:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:29:58 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-njLpUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:29:58 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)) 04:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:29:58 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-507rId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:29:58 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)) 04:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:29:58 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-UrzYLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:29:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 04:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:29:58 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-oaweB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:29:59 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)) 04:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:29:59 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-UEMbHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:29:59 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)) 04:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:29:59 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-GqOmh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:29:59 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)) 04:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:29:59 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-uBZgav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:29:59 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)) 04:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:59 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-qiZpf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:29:59 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)) 04:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:29:59 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-zi_dNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:29:59 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)) 04:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:29:59 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-3jYPFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:29:59 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)) 04:29:59 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)) 04:29:59 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)) 04:29:59 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)) 04:29:59 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)) 04:29:59 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)) 04:29:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:29:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:29:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:29:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:02 INFO - Downloading blessings-1.6.tar.gz 04:30:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:30:02 INFO - Installing collected packages: blessings 04:30:02 INFO - Running setup.py install for blessings 04:30:02 INFO - Successfully installed blessings 04:30:02 INFO - Cleaning up... 04:30:02 INFO - Return code: 0 04:30:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:30:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:30:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:30:02 INFO - Reading from file tmpfile_stdout 04:30:02 INFO - Current package versions: 04:30:02 INFO - blessings == 1.6 04:30:02 INFO - blobuploader == 1.2.4 04:30:02 INFO - docopt == 0.6.1 04:30:02 INFO - manifestparser == 1.1 04:30:02 INFO - mozInstall == 1.12 04:30:02 INFO - mozcrash == 0.17 04:30:02 INFO - mozdebug == 0.1 04:30:02 INFO - mozdevice == 0.48 04:30:02 INFO - mozfile == 1.2 04:30:02 INFO - mozhttpd == 0.7 04:30:02 INFO - mozinfo == 0.9 04:30:02 INFO - mozleak == 0.1 04:30:02 INFO - mozlog == 3.2 04:30:02 INFO - moznetwork == 0.27 04:30:02 INFO - mozprocess == 0.22 04:30:02 INFO - mozprofile == 0.28 04:30:02 INFO - mozrunner == 6.11 04:30:02 INFO - mozscreenshot == 0.1 04:30:02 INFO - mozsystemmonitor == 0.3 04:30:02 INFO - moztest == 0.7 04:30:02 INFO - mozversion == 1.4 04:30:02 INFO - psutil == 3.1.1 04:30:02 INFO - requests == 1.2.3 04:30:02 INFO - wsgiref == 0.1.2 04:30:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30: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')]} 04:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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')]} 04:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:02 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 0x106513a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106519570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1594b6640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b7d30>, '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 0x1061dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063aeb20>, '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.vPBq9aPgrB/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.LGJX3t6brS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 04:30:02 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 04:30:02 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 04:30:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vPBq9aPgrB/Render', 04:30:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:02 INFO - 'HOME': '/Users/cltbld', 04:30:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:02 INFO - 'LOGNAME': 'cltbld', 04:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:02 INFO - 'MOZ_NO_REMOTE': '1', 04:30:02 INFO - 'NO_EM_RESTART': '1', 04:30:02 INFO - 'PAGER': '/bin/cat', 04:30:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:02 INFO - 'PWD': '/builds/slave/test', 04:30:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:02 INFO - 'SHELL': '/bin/bash', 04:30:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LGJX3t6brS/Listeners', 04:30:02 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 04:30:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:02 INFO - 'USER': 'cltbld', 04:30:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:02 INFO - 'XPC_FLAGS': '0x0', 04:30:02 INFO - 'XPC_SERVICE_NAME': '0', 04:30:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:03 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)) 04:30:03 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)) 04:30:03 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)) 04:30:03 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)) 04:30:03 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)) 04:30:03 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)) 04:30:03 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)) 04:30:03 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)) 04:30:03 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)) 04:30:03 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)) 04:30:03 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)) 04:30:03 INFO - Cleaning up... 04:30:03 INFO - Return code: 0 04:30:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:30:03 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')]} 04:30:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:03 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')]} 04:30:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:03 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 0x106513a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106519570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1594b6640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b7d30>, '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 0x1061dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063aeb20>, '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.vPBq9aPgrB/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.LGJX3t6brS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 04:30:03 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 04:30:03 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 04:30:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vPBq9aPgrB/Render', 04:30:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:03 INFO - 'HOME': '/Users/cltbld', 04:30:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:03 INFO - 'LOGNAME': 'cltbld', 04:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:03 INFO - 'MOZ_NO_REMOTE': '1', 04:30:03 INFO - 'NO_EM_RESTART': '1', 04:30:03 INFO - 'PAGER': '/bin/cat', 04:30:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:03 INFO - 'PWD': '/builds/slave/test', 04:30:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:03 INFO - 'SHELL': '/bin/bash', 04:30:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LGJX3t6brS/Listeners', 04:30:03 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 04:30:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:03 INFO - 'USER': 'cltbld', 04:30:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:03 INFO - 'XPC_FLAGS': '0x0', 04:30:03 INFO - 'XPC_SERVICE_NAME': '0', 04:30:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:03 INFO - Downloading/unpacking jsonschema 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:30:05 INFO - Downloading/unpacking functools32 (from jsonschema) 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30:06 INFO - Downloading functools32-3.2.3-2.tar.gz 04:30:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:30:06 INFO - warning: no files found matching '*.txt' 04:30:06 INFO - no previously-included directories found matching 'build' 04:30:06 INFO - no previously-included directories found matching 'dist' 04:30:06 INFO - no previously-included directories found matching '.git*' 04:30:06 INFO - Installing collected packages: jsonschema, functools32 04:30:06 INFO - Running setup.py install for functools32 04:30:06 INFO - warning: no files found matching '*.txt' 04:30:06 INFO - no previously-included directories found matching 'build' 04:30:06 INFO - no previously-included directories found matching 'dist' 04:30:06 INFO - no previously-included directories found matching '.git*' 04:30:06 INFO - Successfully installed jsonschema functools32 04:30:06 INFO - Cleaning up... 04:30:06 INFO - Return code: 0 04:30:06 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:30:06 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')]} 04:30:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:06 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')]} 04:30:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:06 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 0x106513a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106519570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb1594b6640>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b7d30>, '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 0x1061dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063aeb20>, '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.vPBq9aPgrB/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.LGJX3t6brS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 04:30:06 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 04:30:06 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 04:30:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vPBq9aPgrB/Render', 04:30:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:06 INFO - 'HOME': '/Users/cltbld', 04:30:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:06 INFO - 'LOGNAME': 'cltbld', 04:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:06 INFO - 'MOZ_NO_REMOTE': '1', 04:30:06 INFO - 'NO_EM_RESTART': '1', 04:30:06 INFO - 'PAGER': '/bin/cat', 04:30:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:06 INFO - 'PWD': '/builds/slave/test', 04:30:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:06 INFO - 'SHELL': '/bin/bash', 04:30:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LGJX3t6brS/Listeners', 04:30:06 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 04:30:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:06 INFO - 'USER': 'cltbld', 04:30:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:06 INFO - 'XPC_FLAGS': '0x0', 04:30:06 INFO - 'XPC_SERVICE_NAME': '0', 04:30:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:06 INFO - Downloading/unpacking flake8 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:30:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:30:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:30:10 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 04:30:10 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 04:30:10 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 04:30:10 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 04:30:10 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 04:30:10 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 04:30:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:30:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:30:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:30:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:30:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:30:10 INFO - no previously-included directories found matching 'docs/_build' 04:30:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:30:10 INFO - Running setup.py install for pep8 04:30:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:30:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:30:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:30:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:30:10 INFO - no previously-included directories found matching 'docs/_build' 04:30:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:30:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:30:10 INFO - Cleaning up... 04:30:10 INFO - Return code: 0 04:30:10 INFO - Running post-action listener: _resource_record_post_action 04:30:10 INFO - Running post-action listener: _start_resource_monitoring 04:30:10 INFO - Starting resource monitoring. 04:30:10 INFO - ##### 04:30:10 INFO - ##### Finished create-virtualenv step (success) 04:30:10 INFO - ##### 04:30:10 INFO - ##### 04:30:10 INFO - ##### Running install step. 04:30:10 INFO - ##### 04:30:10 INFO - Running pre-action listener: _resource_record_pre_action 04:30:10 INFO - Running main action method: install 04:30:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:30:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:30:11 INFO - Reading from file tmpfile_stdout 04:30:11 INFO - Detecting whether we're running mozinstall >=1.0... 04:30:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:30:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:30:11 INFO - Reading from file tmpfile_stdout 04:30:11 INFO - Output received: 04:30:11 INFO - Usage: mozinstall [options] installer 04:30:11 INFO - Options: 04:30:11 INFO - -h, --help show this help message and exit 04:30:11 INFO - -d DEST, --destination=DEST 04:30:11 INFO - Directory to install application into. [default: 04:30:11 INFO - "/builds/slave/test"] 04:30:11 INFO - --app=APP Application being installed. [default: firefox] 04:30:11 INFO - mkdir: /builds/slave/test/build/application 04:30:11 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'] 04:30:11 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 04:30:44 INFO - Reading from file tmpfile_stdout 04:30:44 INFO - Output received: 04:30:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:30:44 INFO - Running post-action listener: _resource_record_post_action 04:30:44 INFO - ##### 04:30:44 INFO - ##### Finished install step (success) 04:30:44 INFO - ##### 04:30:44 INFO - ##### 04:30:44 INFO - ##### Running run-tests step. 04:30:44 INFO - ##### 04:30:44 INFO - Running pre-action listener: _resource_record_pre_action 04:30:44 INFO - Running main action method: run_tests 04:30:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:30:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:30:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:30:44 INFO - 2016-05-19 04:30:44.738 screenresolution[1638:7369] starting screenresolution argv=screenresolution get 04:30:44 INFO - 2016-05-19 04:30:44.740 screenresolution[1638:7369] Display 0: 1600x1200x32@60 04:30:44 INFO - 2016-05-19 04:30:44.746 screenresolution[1639:7371] starting screenresolution argv=screenresolution list 04:30:44 INFO - Available Modes on Display 0 04:30:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:30:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:30:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:30:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:30:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:30:44 INFO - Intel Iris: 04:30:44 INFO - Chipset Model: Intel Iris 04:30:44 INFO - Type: GPU 04:30:44 INFO - Bus: Built-In 04:30:44 INFO - VRAM (Dynamic, Max): 1536 MB 04:30:44 INFO - Vendor: Intel (0x8086) 04:30:44 INFO - Device ID: 0x0a2e 04:30:44 INFO - Revision ID: 0x0009 04:30:44 INFO - Displays: 04:30:44 INFO - Display: 04:30:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:30:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:30:44 INFO - Display Serial Number: 1600x1200 60 04:30:44 INFO - Main Display: Yes 04:30:44 INFO - Mirror: Off 04:30:44 INFO - Online: Yes 04:30:44 INFO - Rotation: Supported 04:30:44 INFO - Return code: 0 04:30:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:30:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:30:44 INFO - Python 2.7.3 04:30:44 INFO - Return code: 0 04:30:44 INFO - grabbing minidump binary from tooltool 04:30: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')]} 04:30:44 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1063b7d30>, '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 0x1061dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1063aeb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:30:44 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 04:30:44 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 04:30:44 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:30:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:30:45 INFO - Return code: 0 04:30:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:30:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:30:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:30:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:30:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:30:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:30:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:30:46 INFO - Return code: 0 04:30:46 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/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0066', '--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 04:30:46 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/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0066 --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 04:30:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vPBq9aPgrB/Render', 04:30:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:46 INFO - 'HOME': '/Users/cltbld', 04:30:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:46 INFO - 'LOGNAME': 'cltbld', 04:30:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:30:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:30:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:46 INFO - 'MOZ_NO_REMOTE': '1', 04:30:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:30:46 INFO - 'NO_EM_RESTART': '1', 04:30:46 INFO - 'PAGER': '/bin/cat', 04:30:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:46 INFO - 'PWD': '/builds/slave/test', 04:30:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:30:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:46 INFO - 'SHELL': '/bin/bash', 04:30:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LGJX3t6brS/Listeners', 04:30:46 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 04:30:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:46 INFO - 'USER': 'cltbld', 04:30:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:46 INFO - 'XPC_FLAGS': '0x0', 04:30:46 INFO - 'XPC_SERVICE_NAME': '0', 04:30:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:46 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/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0066', '--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 04:30:46 INFO - mozversion application_buildid: 20160519032344 04:30:46 INFO - mozversion application_changeset: 029dcd1503478771cb4a6795a6e8f1fec802c0fd 04:30:46 INFO - mozversion application_display_name: Nightly 04:30:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:30:46 INFO - mozversion application_name: Firefox 04:30:46 INFO - mozversion application_remotingname: firefox 04:30:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:30:46 INFO - mozversion application_vendor: Mozilla 04:30:46 INFO - mozversion application_version: 49.0a1 04:30:46 INFO - mozversion platform_buildid: 20160519032344 04:30:46 INFO - mozversion platform_changeset: 029dcd1503478771cb4a6795a6e8f1fec802c0fd 04:30:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:30:46 INFO - mozversion platform_version: 49.0a1 04:30:46 INFO - using testdate: 1463657446 04:30:46 INFO - actual date: 1463657446 04:30:46 INFO - starting webserver on 'localhost:49271' 04:30:46 INFO - SUITE-START | Running 2 tests 04:30:46 INFO - TEST-START | dromaeo_css 04:30:46 INFO - operating with platform_type : mac_ 04:30:46 INFO - Initialising browser for dromaeo_css test... 04:30:46 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmpQtkr0w/profile http://localhost:49271/getInfo.html) 04:30:48 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 04:30:48 INFO - PROCESS | 1657 | colorDepth:24 04:30:48 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 04:30:48 INFO - PROCESS | 1657 | __metrics 04:30:48 INFO - PROCESS | 1657 | 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" 04:30:48 ERROR - PROCESS | 1657 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:30:49 INFO - TEST-INFO | 1657: exit 0 04:30:49 INFO - Browser initialized. 04:30:49 INFO - Running cycle 1/1 for dromaeo_css test... 04:30:49 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmpQtkr0w/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:31:01 INFO - PROCESS | 1659 | RSS: Main: 238821376 04:31:01 INFO - PROCESS | 1659 | 04:31:52 INFO - PROCESS | 1659 | 1463657512135 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] 04:34:03 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 04:34:05 INFO - PROCESS | 1659 | RSS: Main: 355368960 04:34:05 INFO - PROCESS | 1659 | 04:37:08 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 04:37:08 INFO - PROCESS | 1659 | RSS: Main: 373829632 04:37:08 INFO - PROCESS | 1659 | 04:40:11 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 04:40:11 INFO - PROCESS | 1659 | RSS: Main: 365662208 04:40:11 INFO - PROCESS | 1659 | 04:43:13 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 04:43:14 INFO - PROCESS | 1659 | RSS: Main: 365113344 04:43:14 INFO - PROCESS | 1659 | 04:46:17 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 04:46:18 INFO - PROCESS | 1659 | RSS: Main: 331464704 04:46:18 INFO - PROCESS | 1659 | 04:49:21 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 04:49:21 INFO - PROCESS | 1659 | RSS: Main: 386756608 04:49:21 INFO - PROCESS | 1659 | 04:49:21 INFO - PROCESS | 1659 | __start_tp_report 04:49:21 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 04:49:21 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 04:49:21 INFO - PROCESS | 1659 | |i|pagename|runs| 04:49:21 INFO - PROCESS | 1659 | |0;dojo.html;3243.6269828969666;3469.3581687387837;3496.982515087425;3513.752617820672;3543.1496440854203;5648.209250704905;5665.923474539834;5690.5447564194865;5785.855353616156;5793;5690.06113991191;5746.25298711168;5755.827325180251;5757.712114394284;5758.596898217126;5154.999999999991;5183.507566781169;5214.087534681435;5226.425093239741;5285.550728188108;5213.009528189651;5230.790768369261;5234.26720259164;5268.578513718893;5300.496452836975;5241.401372581361;5243.951209758045;5276.604254680895;5279.102552566053;5324.733763311819;5305.230741542474;5307.814226502092;5322.2282768998275;5323.866903327448;5327.893442131174;5119.744012799345;5127.641065125443;5157.226416037587;5247.790088396459;5251.711155886424;5575.442455754433;5595.468430499096;5599.720013999284;5626.774929002835;5653.095504719226;6304.401081897213;6313.968430157865;6318.684065796691;6330.493560515148;6330.841728956767;6149.692515374213;6258.405451482102;6305.968470157666;6319.810405687791;6350.428461438447;5915.1718759373725;5927.466528012414;5929.0513517836935;5970.820875373745;5972.044472884361;6308.905366419507;6326.49388048955;6333.145025421509;6338.366163383625;6342.143810585512;5804.593678442468;5926.318473375531;6096.91979564504;6176.69116544171;6228.564000520011;6213.254409470893;6342.904856427157;6345.524085693592;6347.936520634827;6359.841003974938;6237.251529816451;6266;6293.118963345135;6293.181886354744;6298.653574053471;6125.021800333839;6171.981623032239;6177.81466556004;6211.503079753609;6307.684615769193;4885.193942999436;4943.134951383566;4967.677100988424;4972.254161875748;4996.175631020914;4430.246858034142;4456.22016147173;4458.7324760514475;4475.194464996267;4496.752678602643;5666.093425052054;5682.204491371212;5688.345840228344;5688.459596338341;5710.514606258432;6196.535259855529;6240.906386404207;6260.780872669435;6262.1546091278005;6304.401081897213;5698;5728.226689396878;5733.996550603628;5736.082226843685;5803.042497987784;5451.863703407446;5453.64551304164;5457.399686034533;5464.617476776588;5478.863028424242;6395.392437718409;6404.199475065617;6421.8073457796345;6432.4532414744335;6440.613563186036;6175.4750846176785;6209.720562574786;6279.026750853625;6283.685815709105;6338;5836.066229403275;6000.849978750566;6401.039844023434;6409.679516024087;6425.9036114457385;6217.595856269328;6222.408871157233;6261.592996455216;6277.5605707600025;6287;6176.536759742948;6230.906536401867;6251.343608920998;6304.18045654062;6352.809415717351;4869.561739443467;5790.5946583740415;6170.167027451236;6172.969135154295;6210.782622608187;6001.099835024606;6146.170267013896;6150.630962142286;6161.876762464815;6182.69086545662;5909.615874968285;5959.135925290895;6007.189029480965;6069.2110025696375;6073.635581865102;6151.200343955257;6159.599626024294;6165.167702360124;6187.133801267736;6191.721372538156;5867;5914.881702365842;6076.210092687922;6078.878422431615;6080.37123524347 04:49:21 INFO - PROCESS | 1659 | |1;ext.html;521.1505246448289;525.0417987173407;528.7382745540957;544.4365082139985;574.9535844762531;30156.84921575392;30703.07890763275;41126;42409.57590424103;42497.575024249745;34383;34601.82699086508;34885.82557087211;35100.648993510054;35121;9508.858936927578;9706.854397184048;9712.854307185398;9728.221742260603;9728.978457261992;8464.66141354345;8718.825623487526;11369.204155709103;11646.126540509453;11648.417579121053;11661.533538658443;11681;11734.11994100446;11747;11749.236299640495;8487.61805718738;11581.884181158208;11682.70793230176;11684.12369072323;11686.123540734436;11353.716157096056;11579;11579.652610421701;11580.536778528933;11594.6521604352;39529.40705889414;39992;39992.60007399918;40020.59979400198;40146;3222.951655725166;3269.9509507357407;3279.3769183854993;3287.917802054968;3470.1498132957586;29201;29384.706152938412;29698.554521682192;30289;30302.69697303043;2767.211344766752;2770.9307267318277;2782.8052036357462;2795.496810574096;2874.4682233786816;495024;496969;499008;501676;502195;59164.11253831196;60446.395536044954;60611.69694151589;60638;60707.08939365913;64193.679031604544;64312.35687643157;64362.6781866097;64375.2062314394;64389.35610643834;16866;17088.743668844978;17101.315947362215;17110.657786844193;17138.228779704696;12022.759544809054;12264;13076.738465230832;13248.138870973356;13346.265955372353;15804.893657444098;15930.362785488682;15950.361985520682;16007.439739609057;16102.516924492284;6242.625442473466;6307.873842523216;6308.445079742712;6336.904946425807;6405.935940640534;877.9937842030929;878.6221924572495;970.8009857979098;1053.7471006958274;1127.5320741892024;24189.51620967571;26079.217623471326;29240.415191696047;29979.800807967873;30053.098407047823;1039.838824982129;1148.8422057770345;1159.4724400397968;1216.9634910952686;1229.692576855786;12733.55432559856;13230.73538529224;14638.121712697272;15070.321835517429;15143.697126057417;571994;641103;644761;654430;656848;58891;59810;59879.40120598825;60002.699986499785;60030.69984650136;1307.7580647580228;1345.5156143788424;1436.6552027513112;1448.2830998655522;1449.4999225267266;28981.13056608305;29910;30020.24949376283;30586.44568281061;30795.538066928566;49956.750216248685;50415.49584504108;50512.494875050776;50581.241281381546;50604.74697626488;528326;537207;539322;540082;540965;32752.017439476855;38162.23675526418;38631;38899.30456606618;39436;45808.770956145;45875.31187032264;45880;46082.53917460916;47429.52570474251;36435.089122772144;37710;37776.81111594424;38564.61435385722;38682.80658596689;37014.44478332773;37203.62796372001;37450.19127545153;37551.43672844855;37828.24343513169;36856.39295088114;37044;37241.81379093087;37315.06712332214;37570.8121459391;32466.83766581152;33231.169220769676;33251;33385.66614333923;33401.832990835865;23952.88023559871;24147.517049658556;24266.51466970686;24881.626775598743;24994 04:49:21 INFO - PROCESS | 1659 | |2;jquery.html;382.902359898226;390.61524398467503;393.6221227621483;394.50686641697877;415.02676224254134;13680;20082.799172008275;20767.480812979644;21250.256241031548;21267.723936563798;20428.876411797344;20722.89638551809;20758.377248682526;20795.480112997182;20947.266845660404;5814.941850581504;6094.695265236743;6134.4478996890275;6152.323244443108;6156.322804491502;5798.536117110642;6007.969960150193;6080.057591073369;6114.8777024459705;6166.568340216164;5873.647581145145;6031.939680603204;6035.517158627299;6051.697415129226;6056.878862422749;5808.332041815203;5957.493613042897;6019.849503762397;6021.367756385582;6036.004059330227;5180.404253510819;5200.687958722451;5216.765245563921;5219.460259223538;5224.686518808846;21449.463763405885;25626.743732562623;26577.73422265772;26918.327041823915;27077.1876843695;2282.4179834142356;2442.1452491627965;2664.440467501827;2969.331900322432;3071.969280307213;14797.926010369987;14837;15004.924975375163;15302.387904483918;15765.60585985348;1751.903645299508;1763.3211213682825;1767.3637490503409;1783.144090836393;1820.4629634257926;86947;120756;123579;124137;126446.36776816058;10881.510332034919;11293.378864162385;11428.085753139729;11572.826407603892;11770.176087673952;12466.875331246752;13152.605421837361;13171.407286672124;14305.713885722227;14326;7616.466847320745;8220.342372610177;8267.958660206661;8387.496750195009;8927.598258078402;8866.689665861664;8886.422382545114;8892.911070889339;9022.593983270768;9044.638214471348;5658.773649054074;5938.8218353449465;6660.433863121689;6793.558418702769;6843.828904277333;1607.3891921070153;1758.2703178181168;1791.4478765416395;1810.5195881642235;1912.92348306069;929.2473096791621;938.6526985015452;973.8928717841031;991.439836492394;1011.9747006324753;21468.570628587655;21882.78117218839;21888.45278868041;22068.2276120335;22152.778472215396;989.3322007644955;999.2256001598819;1064.7976884392097;1099.4777480696605;1128.5090985421368;11237;11697;11716.472758725879;11739.530418783324;11747.353895535656;594391;616526;619768;627597;635377;90279;115904.26143607852;119568.8043119575;121643.39178304229;125360.74639253673;1051.295631926611;1067.295584913953;1070.7537266428765;1073.2433634287809;1088.9891101089047;19023.619527609648;19302.7104593434;19376.031198440305;19496.512587185432;19499.220031198594;50750.74624626853;50999.866321413836;51258.23112653239;52298;53179.468205319;73085.9037114433;74963.25036749562;75509;75703.62148189444;76858;7081.82295442618;7199.928000719926;7541.773746787605;7575.507592006502;7919.16848730875;10456.738581535523;12793.872061279268;12912.612621621367;13222.669433264246;13303.26832024248;11142.442877856236;11210.43947802623;11264.493097810782;11554.826677599674;11666.824997624874;10307.94846025765;11052;11123.72190695239;11131.330300454996;11420.94289528547;11267.154963377616;11300.09599232043;11502;11598.768024639628;11857.288562686266;7486.5508069516;7589.43079269046;7719.266669666597;7726.536407815549;7842.568658723825;10677.51951162184;10703.785924281627;10924.399158046384;11273.661790146309;11280.830787538358 04:49:21 INFO - PROCESS | 1659 | |3;mootools.html;1383.633337165652;1595.768613551035;1626.991865040675;1852;1980.43557586088;8099.595020248986;8580.485170889744;8582.91417085829;8589.742307730778;8594.140585941403;8373.204545568162;8829.205371516562;9017;9033.09669033098;9125.49809760463;2469.0370755405406;2481.615349620807;2508.423062695581;2512.032867346076;2516.106782092349;2426.259990702835;2471.4933438645035;2471.8393304435244;2474.8886300116446;2475.5667758142345;2096.863703859252;2467.198160597804;2486.105002199207;2499.6250562415603;2504.949901001979;2431.732509423971;2440.3777036855577;2473.492933948536;2478.925632231036;2492.513959777844;2476.566600844863;2481.528509583173;2512.196097248881;2514.220591616605;2517.546841568517;9066.002739698628;9135.314851386172;9145.359824812265;9146.954265228698;9154.176124148791;5371.194320851864;5430.782768689248;5617.354004289518;5620.4098569650005;5623.690697011662;3535.840887160084;3626.564812222524;3728.7017038636845;3754.699624030098;3766.0020094674933;1040.7866387390643;1050.144132531984;1086.6033897627387;1092.2136062035402;1099.2744788439672;12171.939140304241;12294.446749896366;12300.138990270685;12319;12327.815082773766;4332.761698106571;5034.395872495324;5200.713960732196;5203.0114278287;5210.687358758487;10280.228982826318;10369.844452333222;10458;10531;10533.736656583493;5614.326280846243;5653.152027195872;5653.434656534314;5657.43425657431;5674.120511320752;4013.7356732629128;5038.244263360452;5110.514501122388;5156.355455568054;5156.74216289184;4121.361189015707;4144.730592511477;4216.430781844411;4249.638780703613;4266.722663026893;1139.8575178102737;1254.2286493806243;1304.0741073837492;1386.8058471813954;1398.489551313763;866.5883705240086;917.4632839788665;925.4169872980115;928.3872644054892;953.5661274120258;5903;5903.852403689943;5926.377730338339;6025.246844144482;6207.023606208034;466.7292970077347;479.14472666290504;487.999600818321;490.3380436410817;490.4776413120016;3656.780593164419;3762.4544441055896;3934.055826601595;4050.311447054008;4084.4690190275073;10742.462876856125;11066.667999960015;11103.666889993314;11145.665630031112;11152.386618735885;9737.123658870594;9963.402195868272;10952.507137178845;11055.336679799238;11098.389588572705;7470.962645186848;8282.544460054754;8552.572371381406;8561.443506172203;8609.65561377538;5863.20846685692;5954.345022047484;5987.311459182076;6093.29927058394;6147.108669242845;10156.035176658206;10211.846822297523;10279.94860025695;10382.948085259526;10412.958704129527;10697.57209711634;10701.625443109453;10727.570897164025;10728.73178170552;10739.301945373525;7709.4603377763015;7766.689332426639;7783.143854175923;7798.922010779819;7844.21557844211;8449.155084491747;8456.95771521163;8542;8551.187637174458;8552.743417697478;7451.664675089525;7602.695892164252;7652.61736913141;7760.417968652261;7761.92238077612;7450.8882366763455;7513.812154695957;7581.090269167735;7599.354054905395;7680.5391676499585;6937;7226.458015648953;7296.1974182838785;7303.780886573411;7310.415166786751;4942.456329803791;4999.325091112653;5052;5092.618053645917;5094.439611642642;4837.153498137742;5054.74726263678;5087.542121209109;5162.767675454538;5269.710165940756 04:49:21 INFO - PROCESS | 1659 | |4;prototype.html;311.6665168269952;312.13538498359543;320.4168413487454;325.38502230783826;328.75507746728675;4887.169181239189;5319.111715341056;5384.7846086156505;5414.756335964881;5417.566594672427;4233.470816147981;4915.6067514598835;5365.973170134144;5385.730713464331;5416.51251387375;3337.5160601712737;3805.8858234253016;4039.1921615676833;4055.2497787909333;4064.4148781975055;3683;4007.4589930359466;4082.142750022484;4087.0395457067566;4094.8362065517495;3808.657220850123;4039.333509970851;4050.453188819501;4083.816228269735;4094.6314831665145;3846.3076646203954;4071.531773846016;4083.8774836754947;4091.7954102295066;4094.5700701426517;3270;3427.657234276577;3534.770239934396;3558.2349794794245;3558.430651095812;5077.339945807022;5085.872853178663;5123.513266239701;5195.376554813409;5214.348206474191;4281;4633.4208223972;4634.281686338623;4658.440987081572;4665.020345727401;5203.687778733288;5542.2795036644975;5566.554675625981;5567.582431317667;5595.580331475156;3599.748017638766;3712.7586706863967;3838.8310604508365;3873.748206366577;3912.589178136283;4602.6087782538525;5685.1756495307945;5709.857253568694;5833.941660583382;5901.734421951022;4026.4564283821896;4033.1731994940965;4036.5963403659985;4064.227796718614;4078.490188726409;3711.832967516468;4046.8924042096705;4133.4626498555;4134.1731653669385;4184.895377615523;4158.189153115113;4500.549945005473;4634.791434385461;4662.7668616569035;4773.9761301193275;5174.663646862942;5192.662476938987;5301.390340110859;5595.580331475156;5623.184638227434;1393.9163650181022;1427.093795439903;1433.1401159304335;1435.131745294103;1438.4249247074438;834.5368320582136;868.6134670071883;878.7609470646486;891.9643214271487;897.2597606974273;3838.405047217686;4157.129291818694;4255.851045213402;4296.89257768552;4357.5162157569885;4553.977230113828;4572.702774319725;4696.759236127062;4769.952300476951;4778.307145463888;3814.7030009796804;3986.870306809141;4096.774677392772;4243.8090285937205;4279.572042795758;2156.967645485319;2409.7831195192516;2512.7361627029263;2579.7230370966117;2579.9293293283454;4547.772611369418;4766.523347665275;5061.367329083864;5067.55406323696;5081.805775642721;4377.802998865059;4422.837671065139;4947.35684361038;4947.621355100761;4997.101101581376;4534.977325113486;4742.762861856893;5070.490107396862;5086.516780905808;5094.8921158093535;4716.551927566876;4729.739864307496;4848.884426929623;4865.440474345495;4944.629152813482;4015.3976903464713;4412.933805992849;4633.513481084572;4762.190427627381;4869.269609558594;4757.5718185363485;5124.615653825904;5195.610329225399;5429.6199266051;6009.669468179117;4000.4399384086832;4028.254772867029;4035.233305671913;4037.9798101009305;4038.2529232092156;4937.185364414831;5049.899002019865;5502.449755024466;5803.738831752666;5837.795677151449;4976.576990955809;5169.379674439091;5344.839654810362;5384.784608615768;5630.8029218977135;4731.597814185695;5550.255485572413;5560.36055853582;5613.691246981455;5626.971865140648;4668.743219122981;5066.847994560169;5089.643724939218;5472.589555783253;5554.472325129106;4261.446012018418;5048.81352882065;5328.893422131613;5618.69097199658;5628.831135065954;5409.727914275934;5484.91772623403;5491.478309560585;5560.527355174693;5566.359868615161 04:49:21 INFO - PROCESS | 1659 | |5;yui.html;641.5733537197763;665.2283351312478;715.6457553511007;729.9817504562385;759.7113097023131;14608.780868286984;15244.695106097872;15289;15298.69402611947;15304.005239659415;14059.437622495088;14099.859001409983;14117.858821411783;14118.152910825358;14159.433622655082;717.921028686844;742.6806473216515;906.9229115525188;931.5388882503171;935.5088578496276;908.9636414543411;919.0396036142247;924.1543987251657;926.305271046715;927.2442958988437;981.9852702209473;986.1863962231153;989;989.5992123190119;989.8663680403162;756.9697212111508;949.8670186173941;979.2557656181282;980.9950950245203;982.9115379615869;732.3262598409476;733.1422235983912;733.5561984999052;735.5219107580116;736.874731295681;14742;14831.629209269746;14832.11007339563;14840;14859;11618.825717614243;12096.516139354415;12152.635420937298;12258.387080645933;12677.556285530054;10209.585605668526;10839.945800270949;10843.349399036084;11016.834747478793;11073.280236784583;3977.960220397817;4003.4795476588147;4004.4193591929297;4143.212789569972;4167.895802604929;78967;79963.20036799675;79999.20000799975;80215.19784802194;80485.39029219441;9195.494247816434;9329.626814927327;9348.90651093494;9435.528223588793;9541.809163816824;20583.485412864797;20713.585728285652;20730.689039664416;20777.376678699664;20789.584208316053;7314.619277204519;7321.231270716605;7443.478956473104;7606.695732170762;7742.380609551335;4039.9596004040172;4097.733647312915;4129.380592911088;4146.087860670647;4210.894727631772;3426.1605906553054;3448.0862571418593;3529;3547.361474934485;3548.0774998500574;470.81402845875687;587.7795826565039;684.1004079635263;701.0395757811831;706.4560288577869;502.83657811211503;552.2489414396417;555.183879696845;577.5379696242989;579.2035950567969;6331.620102793755;7806.297433231037;7926;8123.837523249502;8208.895552223888;541.7291354322839;578.9363170051291;585.1066476274865;596.6599038548072;632.9240491141001;3900.3759398496672;3926.0773718176206;3953.8616148434667;3966.8016599170505;4034.435179074932;74421;95970.52014739881;97178.51410743043;99312.00687993028;101325.49337253414;82868.75696864552;84044.57977710072;84408;84957;86602.56698716592;71642.64178679073;71660;71982.56034879273;71994.64002679953;72589;6190.659513726789;7482.513636613603;7560.697572097055;7676.846463070596;7984.913525719011;34461.48307775335;43494.695159145274;43994.78002609967;45379.31931021103;45502;60137.39862601318;62236.75526489536;62456;62699.37300627118;62934.685326573075;9735.853962190715;9831.754206144618;9990.750231244278;10039.397636141855;10357.74105647365;10856.620018299322;11121.944390277997;11241.66275011751;11841.881581184423;12337.814932776195;9740.805183896424;10854.620088296871;11422.771544568895;11547.538098476303;11648.708782280512;11468.598599049326;11542.653720388402;11590.536378544764;11838.775876153013;12503.249805011728;11238.438078096226;11341.09271258281;11363;11370.20408571392;11723.35521546323;9331.300152488455;9339.906600933904;9444.48055356962;9868.901310986797;9914.702558923243;8789.780255493664;9116.954415227881;9499.30694747761;9644.565994530123;9759.609615615296 04:49:21 INFO - PROCESS | 1659 | __end_tp_report 04:49:21 INFO - PROCESS | 1659 | __start_cc_report 04:49:21 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1138 04:49:21 INFO - PROCESS | 1659 | __end_cc_report 04:49:21 INFO - PROCESS | 1659 | __startTimestamp1463658561596__endTimestamp 04:49:21 INFO - PROCESS | 1659 | 04:49:21 INFO - PROCESS | 1659 | ------- Summary: start ------- 04:49:21 INFO - PROCESS | 1659 | Number of tests: 6 04:49:21 INFO - PROCESS | 1659 | 04:49:21 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5775.27 Median:5970.82 stddev:638.25 (10.7%) stddev-sans-first:608.52 04:49:21 INFO - PROCESS | 1659 | Values: 3243.6 3469.4 3497.0 3513.8 3543.1 5648.2 5665.9 5690.5 5785.9 5793.0 5690.1 5746.3 5755.8 5757.7 5758.6 5155.0 5183.5 5214.1 5226.4 5285.6 5213.0 5230.8 5234.3 5268.6 5300.5 5241.4 5244.0 5276.6 5279.1 5324.7 5305.2 5307.8 5322.2 5323.9 5327.9 5119.7 5127.6 5157.2 5247.8 5251.7 5575.4 5595.5 5599.7 5626.8 5653.1 6304.4 6314.0 6318.7 6330.5 6330.8 6149.7 6258.4 6306.0 6319.8 6350.4 5915.2 5927.5 5929.1 5970.8 5972.0 6308.9 6326.5 6333.1 6338.4 6342.1 5804.6 5926.3 6096.9 6176.7 6228.6 6213.3 6342.9 6345.5 6347.9 6359.8 6237.3 6266.0 6293.1 6293.2 6298.7 6125.0 6172.0 6177.8 6211.5 6307.7 4885.2 4943.1 4967.7 4972.3 4996.2 4430.2 4456.2 4458.7 4475.2 4496.8 5666.1 5682.2 5688.3 5688.5 5710.5 6196.5 6240.9 6260.8 6262.2 6304.4 5698.0 5728.2 5734.0 5736.1 5803.0 5451.9 5453.6 5457.4 5464.6 5478.9 6395.4 6404.2 6421.8 6432.5 6440.6 6175.5 6209.7 6279.0 6283.7 6338.0 5836.1 6000.8 6401.0 6409.7 6425.9 6217.6 6222.4 6261.6 6277.6 6287.0 6176.5 6230.9 6251.3 6304.2 6352.8 4869.6 5790.6 6170.2 6173.0 6210.8 6001.1 6146.2 6150.6 6161.9 6182.7 5909.6 5959.1 6007.2 6069.2 6073.6 6151.2 6159.6 6165.2 6187.1 6191.7 5867.0 5914.9 6076.2 6078.9 6080.4 04:49:21 INFO - PROCESS | 1659 | 04:49:21 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:69146.41 Median:35110.82 stddev:149478.11 (425.7%) stddev-sans-first:149808.63 04:49:21 INFO - PROCESS | 1659 | Values: 521.2 525.0 528.7 544.4 575.0 30156.8 30703.1 41126.0 42409.6 42497.6 34383.0 34601.8 34885.8 35100.6 35121.0 9508.9 9706.9 9712.9 9728.2 9729.0 8464.7 8718.8 11369.2 11646.1 11648.4 11661.5 11681.0 11734.1 11747.0 11749.2 8487.6 11581.9 11682.7 11684.1 11686.1 11353.7 11579.0 11579.7 11580.5 11594.7 39529.4 39992.0 39992.6 40020.6 40146.0 3223.0 3270.0 3279.4 3287.9 3470.1 29201.0 29384.7 29698.6 30289.0 30302.7 2767.2 2770.9 2782.8 2795.5 2874.5 495024.0 496969.0 499008.0 501676.0 502195.0 59164.1 60446.4 60611.7 60638.0 60707.1 64193.7 64312.4 64362.7 64375.2 64389.4 16866.0 17088.7 17101.3 17110.7 17138.2 12022.8 12264.0 13076.7 13248.1 13346.3 15804.9 15930.4 15950.4 16007.4 16102.5 6242.6 6307.9 6308.4 6336.9 6405.9 878.0 878.6 970.8 1053.7 1127.5 24189.5 26079.2 29240.4 29979.8 30053.1 1039.8 1148.8 1159.5 1217.0 1229.7 12733.6 13230.7 14638.1 15070.3 15143.7 571994.0 641103.0 644761.0 654430.0 656848.0 58891.0 59810.0 59879.4 60002.7 60030.7 1307.8 1345.5 1436.7 1448.3 1449.5 28981.1 29910.0 30020.2 30586.4 30795.5 49956.8 50415.5 50512.5 50581.2 50604.7 528326.0 537207.0 539322.0 540082.0 540965.0 32752.0 38162.2 38631.0 38899.3 39436.0 45808.8 45875.3 45880.0 46082.5 47429.5 36435.1 37710.0 37776.8 38564.6 38682.8 37014.4 37203.6 37450.2 37551.4 37828.2 36856.4 37044.0 37241.8 37315.1 37570.8 32466.8 33231.2 33251.0 33385.7 33401.8 23952.9 24147.5 24266.5 24881.6 24994.0 04:49:21 INFO - PROCESS | 1659 | 04:49:21 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:35285.43 Median:21978.34 stddev:102718.48 (467.4%) stddev-sans-first:102973.20 04:49:21 INFO - PROCESS | 1659 | Values: 382.9 390.6 393.6 394.5 415.0 13680.0 20082.8 20767.5 21250.3 21267.7 20428.9 20722.9 20758.4 20795.5 20947.3 5814.9 6094.7 6134.4 6152.3 6156.3 5798.5 6008.0 6080.1 6114.9 6166.6 5873.6 6031.9 6035.5 6051.7 6056.9 5808.3 5957.5 6019.8 6021.4 6036.0 5180.4 5200.7 5216.8 5219.5 5224.7 21449.5 25626.7 26577.7 26918.3 27077.2 2282.4 2442.1 2664.4 2969.3 3072.0 14797.9 14837.0 15004.9 15302.4 15765.6 1751.9 1763.3 1767.4 1783.1 1820.5 86947.0 120756.0 123579.0 124137.0 126446.4 10881.5 11293.4 11428.1 11572.8 11770.2 12466.9 13152.6 13171.4 14305.7 14326.0 7616.5 8220.3 8268.0 8387.5 8927.6 8866.7 8886.4 8892.9 9022.6 9044.6 5658.8 5938.8 6660.4 6793.6 6843.8 1607.4 1758.3 1791.4 1810.5 1912.9 929.2 938.7 973.9 991.4 1012.0 21468.6 21882.8 21888.5 22068.2 22152.8 989.3 999.2 1064.8 1099.5 1128.5 11237.0 11697.0 11716.5 11739.5 11747.4 594391.0 616526.0 619768.0 627597.0 635377.0 90279.0 115904.3 119568.8 121643.4 125360.7 1051.3 1067.3 1070.8 1073.2 1089.0 19023.6 19302.7 19376.0 19496.5 19499.2 50750.7 50999.9 51258.2 52298.0 53179.5 73085.9 74963.3 75509.0 75703.6 76858.0 7081.8 7199.9 7541.8 7575.5 7919.2 10456.7 12793.9 12912.6 13222.7 13303.3 11142.4 11210.4 11264.5 11554.8 11666.8 10307.9 11052.0 11123.7 11131.3 11420.9 11267.2 11300.1 11502.0 11598.8 11857.3 7486.6 7589.4 7719.3 7726.5 7842.6 10677.5 10703.8 10924.4 11273.7 11280.8 04:49:21 INFO - PROCESS | 1659 | 04:49:21 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5877.94 Median:2716.47 stddev:3314.82 (122.0%) stddev-sans-first:3306.90 04:49:21 INFO - PROCESS | 1659 | Values: 1383.6 1595.8 1627.0 1852.0 1980.4 8099.6 8580.5 8582.9 8589.7 8594.1 8373.2 8829.2 9017.0 9033.1 9125.5 2469.0 2481.6 2508.4 2512.0 2516.1 2426.3 2471.5 2471.8 2474.9 2475.6 2096.9 2467.2 2486.1 2499.6 2504.9 2431.7 2440.4 2473.5 2478.9 2492.5 2476.6 2481.5 2512.2 2514.2 2517.5 9066.0 9135.3 9145.4 9147.0 9154.2 5371.2 5430.8 5617.4 5620.4 5623.7 3535.8 3626.6 3728.7 3754.7 3766.0 1040.8 1050.1 1086.6 1092.2 1099.3 12171.9 12294.4 12300.1 12319.0 12327.8 4332.8 5034.4 5200.7 5203.0 5210.7 10280.2 10369.8 10458.0 10531.0 10533.7 5614.3 5653.2 5653.4 5657.4 5674.1 4013.7 5038.2 5110.5 5156.4 5156.7 4121.4 4144.7 4216.4 4249.6 4266.7 1139.9 1254.2 1304.1 1386.8 1398.5 866.6 917.5 925.4 928.4 953.6 5903.0 5903.9 5926.4 6025.2 6207.0 466.7 479.1 488.0 490.3 490.5 3656.8 3762.5 3934.1 4050.3 4084.5 10742.5 11066.7 11103.7 11145.7 11152.4 9737.1 9963.4 10952.5 11055.3 11098.4 7471.0 8282.5 8552.6 8561.4 8609.7 5863.2 5954.3 5987.3 6093.3 6147.1 10156.0 10211.8 10279.9 10382.9 10413.0 10697.6 10701.6 10727.6 10728.7 10739.3 7709.5 7766.7 7783.1 7798.9 7844.2 8449.2 8457.0 8542.0 8551.2 8552.7 7451.7 7602.7 7652.6 7760.4 7761.9 7450.9 7513.8 7581.1 7599.4 7680.5 6937.0 7226.5 7296.2 7303.8 7310.4 4942.5 4999.3 5052.0 5092.6 5094.4 4837.2 5054.7 5087.5 5162.8 5269.7 04:49:21 INFO - PROCESS | 1659 | 04:49:21 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4320.03 Median:4666.88 stddev:1276.49 (27.4%) stddev-sans-first:1244.11 04:49:21 INFO - PROCESS | 1659 | Values: 311.7 312.1 320.4 325.4 328.8 4887.2 5319.1 5384.8 5414.8 5417.6 4233.5 4915.6 5366.0 5385.7 5416.5 3337.5 3805.9 4039.2 4055.2 4064.4 3683.0 4007.5 4082.1 4087.0 4094.8 3808.7 4039.3 4050.5 4083.8 4094.6 3846.3 4071.5 4083.9 4091.8 4094.6 3270.0 3427.7 3534.8 3558.2 3558.4 5077.3 5085.9 5123.5 5195.4 5214.3 4281.0 4633.4 4634.3 4658.4 4665.0 5203.7 5542.3 5566.6 5567.6 5595.6 3599.7 3712.8 3838.8 3873.7 3912.6 4602.6 5685.2 5709.9 5833.9 5901.7 4026.5 4033.2 4036.6 4064.2 4078.5 3711.8 4046.9 4133.5 4134.2 4184.9 4158.2 4500.5 4634.8 4662.8 4774.0 5174.7 5192.7 5301.4 5595.6 5623.2 1393.9 1427.1 1433.1 1435.1 1438.4 834.5 868.6 878.8 892.0 897.3 3838.4 4157.1 4255.9 4296.9 4357.5 4554.0 4572.7 4696.8 4770.0 4778.3 3814.7 3986.9 4096.8 4243.8 4279.6 2157.0 2409.8 2512.7 2579.7 2579.9 4547.8 4766.5 5061.4 5067.6 5081.8 4377.8 4422.8 4947.4 4947.6 4997.1 4535.0 4742.8 5070.5 5086.5 5094.9 4716.6 4729.7 4848.9 4865.4 4944.6 4015.4 4412.9 4633.5 4762.2 4869.3 4757.6 5124.6 5195.6 5429.6 6009.7 4000.4 4028.3 4035.2 4038.0 4038.3 4937.2 5049.9 5502.4 5803.7 5837.8 4976.6 5169.4 5344.8 5384.8 5630.8 4731.6 5550.3 5560.4 5613.7 5627.0 4668.7 5066.8 5089.6 5472.6 5554.5 4261.4 5048.8 5328.9 5618.7 5628.8 5409.7 5484.9 5491.5 5560.5 5566.4 04:49:21 INFO - PROCESS | 1659 | 04:49:21 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:18100.92 Median:62346.38 stddev:25864.27 (41.5%) stddev-sans-first:25903.60 04:49:21 INFO - PROCESS | 1659 | Values: 641.6 665.2 715.6 730.0 759.7 14608.8 15244.7 15289.0 15298.7 15304.0 14059.4 14099.9 14117.9 14118.2 14159.4 717.9 742.7 906.9 931.5 935.5 909.0 919.0 924.2 926.3 927.2 982.0 986.2 989.0 989.6 989.9 757.0 949.9 979.3 981.0 982.9 732.3 733.1 733.6 735.5 736.9 14742.0 14831.6 14832.1 14840.0 14859.0 11618.8 12096.5 12152.6 12258.4 12677.6 10209.6 10839.9 10843.3 11016.8 11073.3 3978.0 4003.5 4004.4 4143.2 4167.9 78967.0 79963.2 79999.2 80215.2 80485.4 9195.5 9329.6 9348.9 9435.5 9541.8 20583.5 20713.6 20730.7 20777.4 20789.6 7314.6 7321.2 7443.5 7606.7 7742.4 4040.0 4097.7 4129.4 4146.1 4210.9 3426.2 3448.1 3529.0 3547.4 3548.1 470.8 587.8 684.1 701.0 706.5 502.8 552.2 555.2 577.5 579.2 6331.6 7806.3 7926.0 8123.8 8208.9 541.7 578.9 585.1 596.7 632.9 3900.4 3926.1 3953.9 3966.8 4034.4 74421.0 95970.5 97178.5 99312.0 101325.5 82868.8 84044.6 84408.0 84957.0 86602.6 71642.6 71660.0 71982.6 71994.6 72589.0 6190.7 7482.5 7560.7 7676.8 7984.9 34461.5 43494.7 43994.8 45379.3 45502.0 60137.4 62236.8 62456.0 62699.4 62934.7 9735.9 9831.8 9990.8 10039.4 10357.7 10856.6 11121.9 11241.7 11841.9 12337.8 9740.8 10854.6 11422.8 11547.5 11648.7 11468.6 11542.7 11590.5 11838.8 12503.2 11238.4 11341.1 11363.0 11370.2 11723.4 9331.3 9339.9 9444.5 9868.9 9914.7 8789.8 9117.0 9499.3 9644.6 9759.6 04:49:21 INFO - PROCESS | 1659 | -------- Summary: end -------- 04:49:21 INFO - PROCESS | 1659 | 04:49:22 INFO - TEST-INFO | 1659: exit 0 04:49:22 INFO - TEST-OK | dromaeo_css | took 1115744ms 04:49:22 INFO - TEST-START | kraken 04:49:22 INFO - operating with platform_type : mac_ 04:49:22 INFO - Initialising browser for kraken test... 04:49:22 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmpMR_MOA/profile http://localhost:49271/getInfo.html) 04:49:24 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 04:49:24 INFO - PROCESS | 1668 | colorDepth:24 04:49:24 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 04:49:24 INFO - PROCESS | 1668 | __metrics 04:49:24 INFO - PROCESS | 1668 | 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" 04:49:24 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:49:25 INFO - TEST-INFO | 1668: exit 0 04:49:25 INFO - Browser initialized. 04:49:25 INFO - Running cycle 1/1 for kraken test... 04:49:25 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmpMR_MOA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:49:37 INFO - PROCESS | 1670 | RSS: Main: 239017984 04:49:37 INFO - PROCESS | 1670 | 04:49:40 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 04:49:41 INFO - PROCESS | 1670 | RSS: Main: 287391744 04:49:41 INFO - PROCESS | 1670 | 04:49:43 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 04:49:44 INFO - PROCESS | 1670 | RSS: Main: 296235008 04:49:44 INFO - PROCESS | 1670 | 04:49:47 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 04:49:48 INFO - PROCESS | 1670 | RSS: Main: 387792896 04:49:48 INFO - PROCESS | 1670 | 04:49:50 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 04:49:51 INFO - PROCESS | 1670 | RSS: Main: 283406336 04:49:51 INFO - PROCESS | 1670 | 04:49:53 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:49:54 INFO - PROCESS | 1670 | RSS: Main: 286744576 04:49:54 INFO - PROCESS | 1670 | 04:49:57 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 04:49:57 INFO - PROCESS | 1670 | RSS: Main: 324378624 04:49:57 INFO - PROCESS | 1670 | 04:50:00 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 04:50:01 INFO - PROCESS | 1670 | RSS: Main: 328048640 04:50:01 INFO - PROCESS | 1670 | 04:50:04 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 04:50:05 INFO - PROCESS | 1670 | RSS: Main: 328896512 04:50:05 INFO - PROCESS | 1670 | 04:50:06 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:50:07 INFO - PROCESS | 1670 | RSS: Main: 316059648 04:50:07 INFO - PROCESS | 1670 | 04:50:09 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:50:10 INFO - PROCESS | 1670 | RSS: Main: 323264512 04:50:10 INFO - PROCESS | 1670 | 04:50:12 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:50:13 INFO - PROCESS | 1670 | RSS: Main: 326512640 04:50:13 INFO - PROCESS | 1670 | 04:50:16 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:50:16 INFO - PROCESS | 1670 | RSS: Main: 330240000 04:50:16 INFO - PROCESS | 1670 | 04:50:19 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:50:20 INFO - PROCESS | 1670 | RSS: Main: 328101888 04:50:20 INFO - PROCESS | 1670 | 04:50:22 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 04:50:22 INFO - PROCESS | 1670 | RSS: Main: 375115776 04:50:22 INFO - PROCESS | 1670 | 04:50:22 INFO - PROCESS | 1670 | __start_tp_report 04:50:22 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 04:50:22 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 04:50:22 INFO - PROCESS | 1670 | |i|pagename|runs| 04:50:22 INFO - PROCESS | 1670 | |0;ai-astar;111;106;111;111;111;112;111;112;111;109 04:50:22 INFO - PROCESS | 1670 | |1;audio-beat-detection;153;123;138;196;118;136;116;149;155;117 04:50:22 INFO - PROCESS | 1670 | |2;audio-dft;181;186;186;184;185;183;184;185;182;182 04:50:22 INFO - PROCESS | 1670 | |3;audio-fft;78;73;59;151;79;88;57;58;110;56 04:50:22 INFO - PROCESS | 1670 | |4;audio-oscillator;91;93;133;81;80;76;120;113;116;106 04:50:22 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;86;84;87;83;87;83;86;84;86;83 04:50:22 INFO - PROCESS | 1670 | |6;imaging-darkroom;93;92;93;93;91;92;93;94;92;92 04:50:22 INFO - PROCESS | 1670 | |7;imaging-desaturate;79;84;84;86;94;134;84;83;85;86 04:50:22 INFO - PROCESS | 1670 | |8;json-parse-financial;57;56;56;55;55;56;55;117;56;54 04:50:22 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;63;71;74;70;69;66;66;66;67;66 04:50:22 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;86;85;85;84;85;83;84;86;85;85 04:50:22 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;139;137;138;140;150;142;151;137;237;152 04:50:22 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;198;222;144;204;221;213;164;165;162;161 04:50:22 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;67;66;66;66;67;65;65;66;64;65 04:50:22 INFO - PROCESS | 1670 | __end_tp_report 04:50:22 INFO - PROCESS | 1670 | __start_cc_report 04:50:22 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,2287 04:50:22 INFO - PROCESS | 1670 | __end_cc_report 04:50:22 INFO - PROCESS | 1670 | __startTimestamp1463658622529__endTimestamp 04:50:22 INFO - PROCESS | 1670 | 04:50:22 INFO - PROCESS | 1670 | ------- Summary: start ------- 04:50:22 INFO - PROCESS | 1670 | Number of tests: 14 04:50:22 INFO - PROCESS | 1670 | 04:50:22 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.00 stddev:1.78 (1.6%) stddev-sans-first:1.88 04:50:22 INFO - PROCESS | 1670 | Values: 111.0 106.0 111.0 111.0 111.0 112.0 111.0 112.0 111.0 109.0 04:50:22 INFO - PROCESS | 1670 | 04:50:22 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:143.50 stddev:24.74 (17.2%) stddev-sans-first:25.80 04:50:22 INFO - PROCESS | 1670 | Values: 153.0 123.0 138.0 196.0 118.0 136.0 116.0 149.0 155.0 117.0 04:50:22 INFO - PROCESS | 1670 | 04:50:22 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.50 stddev:1.75 (0.9%) stddev-sans-first:1.54 04:50:22 INFO - PROCESS | 1670 | Values: 181.0 186.0 186.0 184.0 185.0 183.0 184.0 185.0 182.0 182.0 04:50:22 INFO - PROCESS | 1670 | 04:50:22 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:80.90 Median:66.00 stddev:29.96 (45.4%) stddev-sans-first:31.76 04:50:22 INFO - PROCESS | 1670 | Values: 78.0 73.0 59.0 151.0 79.0 88.0 57.0 58.0 110.0 56.0 04:50:22 INFO - PROCESS | 1670 | 04:50:22 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:100.90 Median:78.00 stddev:19.46 (25.0%) stddev-sans-first:20.31 04:50:22 INFO - PROCESS | 1670 | Values: 91.0 93.0 133.0 81.0 80.0 76.0 120.0 113.0 116.0 106.0 04:50:22 INFO - PROCESS | 1670 | 04:50:22 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:84.90 Median:86.00 stddev:1.66 (1.9%) stddev-sans-first:1.72 04:50:22 INFO - PROCESS | 1670 | Values: 86.0 84.0 87.0 83.0 87.0 83.0 86.0 84.0 86.0 83.0 04:50:22 INFO - PROCESS | 1670 | 04:50:22 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:92.50 Median:93.00 stddev:0.85 (0.9%) stddev-sans-first:0.88 04:50:22 INFO - PROCESS | 1670 | Values: 93.0 92.0 93.0 93.0 91.0 92.0 93.0 94.0 92.0 92.0 04:50:22 INFO - PROCESS | 1670 | 04:50:22 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:89.90 Median:84.50 stddev:15.94 (18.9%) stddev-sans-first:16.41 04:50:22 INFO - PROCESS | 1670 | Values: 79.0 84.0 84.0 86.0 94.0 134.0 84.0 83.0 85.0 86.0 04:50:22 INFO - PROCESS | 1670 | 04:50:22 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:61.70 Median:56.00 stddev:19.45 (34.7%) stddev-sans-first:20.55 04:50:22 INFO - PROCESS | 1670 | Values: 57.0 56.0 56.0 55.0 55.0 56.0 55.0 117.0 56.0 54.0 04:50:22 INFO - PROCESS | 1670 | 04:50:22 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:68.00 stddev:3.19 (4.7%) stddev-sans-first:2.87 04:50:22 INFO - PROCESS | 1670 | Values: 63.0 71.0 74.0 70.0 69.0 66.0 66.0 66.0 67.0 66.0 04:50:22 INFO - PROCESS | 1670 | 04:50:22 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:85.00 stddev:0.92 (1.1%) stddev-sans-first:0.87 04:50:22 INFO - PROCESS | 1670 | Values: 86.0 85.0 85.0 84.0 85.0 83.0 84.0 86.0 85.0 85.0 04:50:22 INFO - PROCESS | 1670 | 04:50:22 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:152.30 Median:146.00 stddev:30.35 (20.8%) stddev-sans-first:31.80 04:50:22 INFO - PROCESS | 1670 | Values: 139.0 137.0 138.0 140.0 150.0 142.0 151.0 137.0 237.0 152.0 04:50:22 INFO - PROCESS | 1670 | 04:50:22 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.40 Median:201.00 stddev:29.07 (14.5%) stddev-sans-first:30.47 04:50:22 INFO - PROCESS | 1670 | Values: 198.0 222.0 144.0 204.0 221.0 213.0 164.0 165.0 162.0 161.0 04:50:22 INFO - PROCESS | 1670 | 04:50:22 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:0.95 (1.4%) stddev-sans-first:0.88 04:50:22 INFO - PROCESS | 1670 | Values: 67.0 66.0 66.0 66.0 67.0 65.0 65.0 66.0 64.0 65.0 04:50:22 INFO - PROCESS | 1670 | -------- Summary: end -------- 04:50:22 INFO - PROCESS | 1670 | 04:50:23 INFO - TEST-INFO | 1670: exit 0 04:50:23 INFO - TEST-OK | kraken | took 61239ms 04:50:23 INFO - SUITE-END | took 1176s 04:50:23 INFO - Completed test suite (00:19:37) 04:50:23 INFO - javascript benchmark 04:50:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3243.6269828969666, 3469.3581687387837, 3496.982515087425, 3513.752617820672, 3543.1496440854203, 5648.209250704905, 5665.923474539834, 5690.5447564194865, 5785.855353616156, 5793.0, 5690.06113991191, 5746.25298711168, 5755.827325180251, 5757.712114394284, 5758.596898217126, 5154.999999999991, 5183.507566781169, 5214.087534681435, 5226.425093239741, 5285.550728188108, 5213.009528189651, 5230.790768369261, 5234.26720259164, 5268.578513718893, 5300.496452836975, 5241.401372581361, 5243.951209758045, 5276.604254680895, 5279.102552566053, 5324.733763311819, 5305.230741542474, 5307.814226502092, 5322.2282768998275, 5323.866903327448, 5327.893442131174, 5119.744012799345, 5127.641065125443, 5157.226416037587, 5247.790088396459, 5251.711155886424, 5575.442455754433, 5595.468430499096, 5599.720013999284, 5626.774929002835, 5653.095504719226, 6304.401081897213, 6313.968430157865, 6318.684065796691, 6330.493560515148, 6330.841728956767, 6149.692515374213, 6258.405451482102, 6305.968470157666, 6319.810405687791, 6350.428461438447, 5915.1718759373725, 5927.466528012414, 5929.0513517836935, 5970.820875373745, 5972.044472884361, 6308.905366419507, 6326.49388048955, 6333.145025421509, 6338.366163383625, 6342.143810585512, 5804.593678442468, 5926.318473375531, 6096.91979564504, 6176.69116544171, 6228.564000520011, 6213.254409470893, 6342.904856427157, 6345.524085693592, 6347.936520634827, 6359.841003974938, 6237.251529816451, 6266.0, 6293.118963345135, 6293.181886354744, 6298.653574053471, 6125.021800333839, 6171.981623032239, 6177.81466556004, 6211.503079753609, 6307.684615769193, 4885.193942999436, 4943.134951383566, 4967.677100988424, 4972.254161875748, 4996.175631020914, 4430.246858034142, 4456.22016147173, 4458.7324760514475, 4475.194464996267, 4496.752678602643, 5666.093425052054, 5682.204491371212, 5688.345840228344, 5688.459596338341, 5710.514606258432, 6196.535259855529, 6240.906386404207, 6260.780872669435, 6262.1546091278005, 6304.401081897213, 5698.0, 5728.226689396878, 5733.996550603628, 5736.082226843685, 5803.042497987784, 5451.863703407446, 5453.64551304164, 5457.399686034533, 5464.617476776588, 5478.863028424242, 6395.392437718409, 6404.199475065617, 6421.8073457796345, 6432.4532414744335, 6440.613563186036, 6175.4750846176785, 6209.720562574786, 6279.026750853625, 6283.685815709105, 6338.0, 5836.066229403275, 6000.849978750566, 6401.039844023434, 6409.679516024087, 6425.9036114457385, 6217.595856269328, 6222.408871157233, 6261.592996455216, 6277.5605707600025, 6287.0, 6176.536759742948, 6230.906536401867, 6251.343608920998, 6304.18045654062, 6352.809415717351, 4869.561739443467, 5790.5946583740415, 6170.167027451236, 6172.969135154295, 6210.782622608187, 6001.099835024606, 6146.170267013896, 6150.630962142286, 6161.876762464815, 6182.69086545662, 5909.615874968285, 5959.135925290895, 6007.189029480965, 6069.2110025696375, 6073.635581865102, 6151.200343955257, 6159.599626024294, 6165.167702360124, 6187.133801267736, 6191.721372538156, 5867.0, 5914.881702365842, 6076.210092687922, 6078.878422431615, 6080.37123524347], "value": 5735.193809231416, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [521.1505246448289, 525.0417987173407, 528.7382745540957, 544.4365082139985, 574.9535844762531, 30156.84921575392, 30703.07890763275, 41126.0, 42409.57590424103, 42497.575024249745, 34383.0, 34601.82699086508, 34885.82557087211, 35100.648993510054, 35121.0, 9508.858936927578, 9706.854397184048, 9712.854307185398, 9728.221742260603, 9728.978457261992, 8464.66141354345, 8718.825623487526, 11369.204155709103, 11646.126540509453, 11648.417579121053, 11661.533538658443, 11681.0, 11734.11994100446, 11747.0, 11749.236299640495, 8487.61805718738, 11581.884181158208, 11682.70793230176, 11684.12369072323, 11686.123540734436, 11353.716157096056, 11579.0, 11579.652610421701, 11580.536778528933, 11594.6521604352, 39529.40705889414, 39992.0, 39992.60007399918, 40020.59979400198, 40146.0, 3222.951655725166, 3269.9509507357407, 3279.3769183854993, 3287.917802054968, 3470.1498132957586, 29201.0, 29384.706152938412, 29698.554521682192, 30289.0, 30302.69697303043, 2767.211344766752, 2770.9307267318277, 2782.8052036357462, 2795.496810574096, 2874.4682233786816, 495024.0, 496969.0, 499008.0, 501676.0, 502195.0, 59164.11253831196, 60446.395536044954, 60611.69694151589, 60638.0, 60707.08939365913, 64193.679031604544, 64312.35687643157, 64362.6781866097, 64375.2062314394, 64389.35610643834, 16866.0, 17088.743668844978, 17101.315947362215, 17110.657786844193, 17138.228779704696, 12022.759544809054, 12264.0, 13076.738465230832, 13248.138870973356, 13346.265955372353, 15804.893657444098, 15930.362785488682, 15950.361985520682, 16007.439739609057, 16102.516924492284, 6242.625442473466, 6307.873842523216, 6308.445079742712, 6336.904946425807, 6405.935940640534, 877.9937842030929, 878.6221924572495, 970.8009857979098, 1053.7471006958274, 1127.5320741892024, 24189.51620967571, 26079.217623471326, 29240.415191696047, 29979.800807967873, 30053.098407047823, 1039.838824982129, 1148.8422057770345, 1159.4724400397968, 1216.9634910952686, 1229.692576855786, 12733.55432559856, 13230.73538529224, 14638.121712697272, 15070.321835517429, 15143.697126057417, 571994.0, 641103.0, 644761.0, 654430.0, 656848.0, 58891.0, 59810.0, 59879.40120598825, 60002.699986499785, 60030.69984650136, 1307.7580647580228, 1345.5156143788424, 1436.6552027513112, 1448.2830998655522, 1449.4999225267266, 28981.13056608305, 29910.0, 30020.24949376283, 30586.44568281061, 30795.538066928566, 49956.750216248685, 50415.49584504108, 50512.494875050776, 50581.241281381546, 50604.74697626488, 528326.0, 537207.0, 539322.0, 540082.0, 540965.0, 32752.017439476855, 38162.23675526418, 38631.0, 38899.30456606618, 39436.0, 45808.770956145, 45875.31187032264, 45880.0, 46082.53917460916, 47429.52570474251, 36435.089122772144, 37710.0, 37776.81111594424, 38564.61435385722, 38682.80658596689, 37014.44478332773, 37203.62796372001, 37450.19127545153, 37551.43672844855, 37828.24343513169, 36856.39295088114, 37044.0, 37241.81379093087, 37315.06712332214, 37570.8121459391, 32466.83766581152, 33231.169220769676, 33251.0, 33385.66614333923, 33401.832990835865, 23952.88023559871, 24147.517049658556, 24266.51466970686, 24881.626775598743, 24994.0], "value": 20017.249898974, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.902359898226, 390.61524398467503, 393.6221227621483, 394.50686641697877, 415.02676224254134, 13680.0, 20082.799172008275, 20767.480812979644, 21250.256241031548, 21267.723936563798, 20428.876411797344, 20722.89638551809, 20758.377248682526, 20795.480112997182, 20947.266845660404, 5814.941850581504, 6094.695265236743, 6134.4478996890275, 6152.323244443108, 6156.322804491502, 5798.536117110642, 6007.969960150193, 6080.057591073369, 6114.8777024459705, 6166.568340216164, 5873.647581145145, 6031.939680603204, 6035.517158627299, 6051.697415129226, 6056.878862422749, 5808.332041815203, 5957.493613042897, 6019.849503762397, 6021.367756385582, 6036.004059330227, 5180.404253510819, 5200.687958722451, 5216.765245563921, 5219.460259223538, 5224.686518808846, 21449.463763405885, 25626.743732562623, 26577.73422265772, 26918.327041823915, 27077.1876843695, 2282.4179834142356, 2442.1452491627965, 2664.440467501827, 2969.331900322432, 3071.969280307213, 14797.926010369987, 14837.0, 15004.924975375163, 15302.387904483918, 15765.60585985348, 1751.903645299508, 1763.3211213682825, 1767.3637490503409, 1783.144090836393, 1820.4629634257926, 86947.0, 120756.0, 123579.0, 124137.0, 126446.36776816058, 10881.510332034919, 11293.378864162385, 11428.085753139729, 11572.826407603892, 11770.176087673952, 12466.875331246752, 13152.605421837361, 13171.407286672124, 14305.713885722227, 14326.0, 7616.466847320745, 8220.342372610177, 8267.958660206661, 8387.496750195009, 8927.598258078402, 8866.689665861664, 8886.422382545114, 8892.911070889339, 9022.593983270768, 9044.638214471348, 5658.773649054074, 5938.8218353449465, 6660.433863121689, 6793.558418702769, 6843.828904277333, 1607.3891921070153, 1758.2703178181168, 1791.4478765416395, 1810.5195881642235, 1912.92348306069, 929.2473096791621, 938.6526985015452, 973.8928717841031, 991.439836492394, 1011.9747006324753, 21468.570628587655, 21882.78117218839, 21888.45278868041, 22068.2276120335, 22152.778472215396, 989.3322007644955, 999.2256001598819, 1064.7976884392097, 1099.4777480696605, 1128.5090985421368, 11237.0, 11697.0, 11716.472758725879, 11739.530418783324, 11747.353895535656, 594391.0, 616526.0, 619768.0, 627597.0, 635377.0, 90279.0, 115904.26143607852, 119568.8043119575, 121643.39178304229, 125360.74639253673, 1051.295631926611, 1067.295584913953, 1070.7537266428765, 1073.2433634287809, 1088.9891101089047, 19023.619527609648, 19302.7104593434, 19376.031198440305, 19496.512587185432, 19499.220031198594, 50750.74624626853, 50999.866321413836, 51258.23112653239, 52298.0, 53179.468205319, 73085.9037114433, 74963.25036749562, 75509.0, 75703.62148189444, 76858.0, 7081.82295442618, 7199.928000719926, 7541.773746787605, 7575.507592006502, 7919.16848730875, 10456.738581535523, 12793.872061279268, 12912.612621621367, 13222.669433264246, 13303.26832024248, 11142.442877856236, 11210.43947802623, 11264.493097810782, 11554.826677599674, 11666.824997624874, 10307.94846025765, 11052.0, 11123.72190695239, 11131.330300454996, 11420.94289528547, 11267.154963377616, 11300.09599232043, 11502.0, 11598.768024639628, 11857.288562686266, 7486.5508069516, 7589.43079269046, 7719.266669666597, 7726.536407815549, 7842.568658723825, 10677.51951162184, 10703.785924281627, 10924.399158046384, 11273.661790146309, 11280.830787538358], "value": 9815.221828546459, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1383.633337165652, 1595.768613551035, 1626.991865040675, 1852.0, 1980.43557586088, 8099.595020248986, 8580.485170889744, 8582.91417085829, 8589.742307730778, 8594.140585941403, 8373.204545568162, 8829.205371516562, 9017.0, 9033.09669033098, 9125.49809760463, 2469.0370755405406, 2481.615349620807, 2508.423062695581, 2512.032867346076, 2516.106782092349, 2426.259990702835, 2471.4933438645035, 2471.8393304435244, 2474.8886300116446, 2475.5667758142345, 2096.863703859252, 2467.198160597804, 2486.105002199207, 2499.6250562415603, 2504.949901001979, 2431.732509423971, 2440.3777036855577, 2473.492933948536, 2478.925632231036, 2492.513959777844, 2476.566600844863, 2481.528509583173, 2512.196097248881, 2514.220591616605, 2517.546841568517, 9066.002739698628, 9135.314851386172, 9145.359824812265, 9146.954265228698, 9154.176124148791, 5371.194320851864, 5430.782768689248, 5617.354004289518, 5620.4098569650005, 5623.690697011662, 3535.840887160084, 3626.564812222524, 3728.7017038636845, 3754.699624030098, 3766.0020094674933, 1040.7866387390643, 1050.144132531984, 1086.6033897627387, 1092.2136062035402, 1099.2744788439672, 12171.939140304241, 12294.446749896366, 12300.138990270685, 12319.0, 12327.815082773766, 4332.761698106571, 5034.395872495324, 5200.713960732196, 5203.0114278287, 5210.687358758487, 10280.228982826318, 10369.844452333222, 10458.0, 10531.0, 10533.736656583493, 5614.326280846243, 5653.152027195872, 5653.434656534314, 5657.43425657431, 5674.120511320752, 4013.7356732629128, 5038.244263360452, 5110.514501122388, 5156.355455568054, 5156.74216289184, 4121.361189015707, 4144.730592511477, 4216.430781844411, 4249.638780703613, 4266.722663026893, 1139.8575178102737, 1254.2286493806243, 1304.0741073837492, 1386.8058471813954, 1398.489551313763, 866.5883705240086, 917.4632839788665, 925.4169872980115, 928.3872644054892, 953.5661274120258, 5903.0, 5903.852403689943, 5926.377730338339, 6025.246844144482, 6207.023606208034, 466.7292970077347, 479.14472666290504, 487.999600818321, 490.3380436410817, 490.4776413120016, 3656.780593164419, 3762.4544441055896, 3934.055826601595, 4050.311447054008, 4084.4690190275073, 10742.462876856125, 11066.667999960015, 11103.666889993314, 11145.665630031112, 11152.386618735885, 9737.123658870594, 9963.402195868272, 10952.507137178845, 11055.336679799238, 11098.389588572705, 7470.962645186848, 8282.544460054754, 8552.572371381406, 8561.443506172203, 8609.65561377538, 5863.20846685692, 5954.345022047484, 5987.311459182076, 6093.29927058394, 6147.108669242845, 10156.035176658206, 10211.846822297523, 10279.94860025695, 10382.948085259526, 10412.958704129527, 10697.57209711634, 10701.625443109453, 10727.570897164025, 10728.73178170552, 10739.301945373525, 7709.4603377763015, 7766.689332426639, 7783.143854175923, 7798.922010779819, 7844.21557844211, 8449.155084491747, 8456.95771521163, 8542.0, 8551.187637174458, 8552.743417697478, 7451.664675089525, 7602.695892164252, 7652.61736913141, 7760.417968652261, 7761.92238077612, 7450.8882366763455, 7513.812154695957, 7581.090269167735, 7599.354054905395, 7680.5391676499585, 6937.0, 7226.458015648953, 7296.1974182838785, 7303.780886573411, 7310.415166786751, 4942.456329803791, 4999.325091112653, 5052.0, 5092.618053645917, 5094.439611642642, 4837.153498137742, 5054.74726263678, 5087.542121209109, 5162.767675454538, 5269.710165940756], "value": 4648.557703920185, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.6665168269952, 312.13538498359543, 320.4168413487454, 325.38502230783826, 328.75507746728675, 4887.169181239189, 5319.111715341056, 5384.7846086156505, 5414.756335964881, 5417.566594672427, 4233.470816147981, 4915.6067514598835, 5365.973170134144, 5385.730713464331, 5416.51251387375, 3337.5160601712737, 3805.8858234253016, 4039.1921615676833, 4055.2497787909333, 4064.4148781975055, 3683.0, 4007.4589930359466, 4082.142750022484, 4087.0395457067566, 4094.8362065517495, 3808.657220850123, 4039.333509970851, 4050.453188819501, 4083.816228269735, 4094.6314831665145, 3846.3076646203954, 4071.531773846016, 4083.8774836754947, 4091.7954102295066, 4094.5700701426517, 3270.0, 3427.657234276577, 3534.770239934396, 3558.2349794794245, 3558.430651095812, 5077.339945807022, 5085.872853178663, 5123.513266239701, 5195.376554813409, 5214.348206474191, 4281.0, 4633.4208223972, 4634.281686338623, 4658.440987081572, 4665.020345727401, 5203.687778733288, 5542.2795036644975, 5566.554675625981, 5567.582431317667, 5595.580331475156, 3599.748017638766, 3712.7586706863967, 3838.8310604508365, 3873.748206366577, 3912.589178136283, 4602.6087782538525, 5685.1756495307945, 5709.857253568694, 5833.941660583382, 5901.734421951022, 4026.4564283821896, 4033.1731994940965, 4036.5963403659985, 4064.227796718614, 4078.490188726409, 3711.832967516468, 4046.8924042096705, 4133.4626498555, 4134.1731653669385, 4184.895377615523, 4158.189153115113, 4500.549945005473, 4634.791434385461, 4662.7668616569035, 4773.9761301193275, 5174.663646862942, 5192.662476938987, 5301.390340110859, 5595.580331475156, 5623.184638227434, 1393.9163650181022, 1427.093795439903, 1433.1401159304335, 1435.131745294103, 1438.4249247074438, 834.5368320582136, 868.6134670071883, 878.7609470646486, 891.9643214271487, 897.2597606974273, 3838.405047217686, 4157.129291818694, 4255.851045213402, 4296.89257768552, 4357.5162157569885, 4553.977230113828, 4572.702774319725, 4696.759236127062, 4769.952300476951, 4778.307145463888, 3814.7030009796804, 3986.870306809141, 4096.774677392772, 4243.8090285937205, 4279.572042795758, 2156.967645485319, 2409.7831195192516, 2512.7361627029263, 2579.7230370966117, 2579.9293293283454, 4547.772611369418, 4766.523347665275, 5061.367329083864, 5067.55406323696, 5081.805775642721, 4377.802998865059, 4422.837671065139, 4947.35684361038, 4947.621355100761, 4997.101101581376, 4534.977325113486, 4742.762861856893, 5070.490107396862, 5086.516780905808, 5094.8921158093535, 4716.551927566876, 4729.739864307496, 4848.884426929623, 4865.440474345495, 4944.629152813482, 4015.3976903464713, 4412.933805992849, 4633.513481084572, 4762.190427627381, 4869.269609558594, 4757.5718185363485, 5124.615653825904, 5195.610329225399, 5429.6199266051, 6009.669468179117, 4000.4399384086832, 4028.254772867029, 4035.233305671913, 4037.9798101009305, 4038.2529232092156, 4937.185364414831, 5049.899002019865, 5502.449755024466, 5803.738831752666, 5837.795677151449, 4976.576990955809, 5169.379674439091, 5344.839654810362, 5384.784608615768, 5630.8029218977135, 4731.597814185695, 5550.255485572413, 5560.36055853582, 5613.691246981455, 5626.971865140648, 4668.743219122981, 5066.847994560169, 5089.643724939218, 5472.589555783253, 5554.472325129106, 4261.446012018418, 5048.81352882065, 5328.893422131613, 5618.69097199658, 5628.831135065954, 5409.727914275934, 5484.91772623403, 5491.478309560585, 5560.527355174693, 5566.359868615161], "value": 3925.7249134153694, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.5733537197763, 665.2283351312478, 715.6457553511007, 729.9817504562385, 759.7113097023131, 14608.780868286984, 15244.695106097872, 15289.0, 15298.69402611947, 15304.005239659415, 14059.437622495088, 14099.859001409983, 14117.858821411783, 14118.152910825358, 14159.433622655082, 717.921028686844, 742.6806473216515, 906.9229115525188, 931.5388882503171, 935.5088578496276, 908.9636414543411, 919.0396036142247, 924.1543987251657, 926.305271046715, 927.2442958988437, 981.9852702209473, 986.1863962231153, 989.0, 989.5992123190119, 989.8663680403162, 756.9697212111508, 949.8670186173941, 979.2557656181282, 980.9950950245203, 982.9115379615869, 732.3262598409476, 733.1422235983912, 733.5561984999052, 735.5219107580116, 736.874731295681, 14742.0, 14831.629209269746, 14832.11007339563, 14840.0, 14859.0, 11618.825717614243, 12096.516139354415, 12152.635420937298, 12258.387080645933, 12677.556285530054, 10209.585605668526, 10839.945800270949, 10843.349399036084, 11016.834747478793, 11073.280236784583, 3977.960220397817, 4003.4795476588147, 4004.4193591929297, 4143.212789569972, 4167.895802604929, 78967.0, 79963.20036799675, 79999.20000799975, 80215.19784802194, 80485.39029219441, 9195.494247816434, 9329.626814927327, 9348.90651093494, 9435.528223588793, 9541.809163816824, 20583.485412864797, 20713.585728285652, 20730.689039664416, 20777.376678699664, 20789.584208316053, 7314.619277204519, 7321.231270716605, 7443.478956473104, 7606.695732170762, 7742.380609551335, 4039.9596004040172, 4097.733647312915, 4129.380592911088, 4146.087860670647, 4210.894727631772, 3426.1605906553054, 3448.0862571418593, 3529.0, 3547.361474934485, 3548.0774998500574, 470.81402845875687, 587.7795826565039, 684.1004079635263, 701.0395757811831, 706.4560288577869, 502.83657811211503, 552.2489414396417, 555.183879696845, 577.5379696242989, 579.2035950567969, 6331.620102793755, 7806.297433231037, 7926.0, 8123.837523249502, 8208.895552223888, 541.7291354322839, 578.9363170051291, 585.1066476274865, 596.6599038548072, 632.9240491141001, 3900.3759398496672, 3926.0773718176206, 3953.8616148434667, 3966.8016599170505, 4034.435179074932, 74421.0, 95970.52014739881, 97178.51410743043, 99312.00687993028, 101325.49337253414, 82868.75696864552, 84044.57977710072, 84408.0, 84957.0, 86602.56698716592, 71642.64178679073, 71660.0, 71982.56034879273, 71994.64002679953, 72589.0, 6190.659513726789, 7482.513636613603, 7560.697572097055, 7676.846463070596, 7984.913525719011, 34461.48307775335, 43494.695159145274, 43994.78002609967, 45379.31931021103, 45502.0, 60137.39862601318, 62236.75526489536, 62456.0, 62699.37300627118, 62934.685326573075, 9735.853962190715, 9831.754206144618, 9990.750231244278, 10039.397636141855, 10357.74105647365, 10856.620018299322, 11121.944390277997, 11241.66275011751, 11841.881581184423, 12337.814932776195, 9740.805183896424, 10854.620088296871, 11422.771544568895, 11547.538098476303, 11648.708782280512, 11468.598599049326, 11542.653720388402, 11590.536378544764, 11838.775876153013, 12503.249805011728, 11238.438078096226, 11341.09271258281, 11363.0, 11370.20408571392, 11723.35521546323, 9331.300152488455, 9339.906600933904, 9444.48055356962, 9868.901310986797, 9914.702558923243, 8789.780255493664, 9116.954415227881, 9499.30694747761, 9644.565994530123, 9759.609615615296], "value": 6833.656753145079, "unit": "score"}], "name": "dromaeo_css", "value": 7210.498528363262, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 106.0, 111.0, 111.0, 111.0, 112.0, 111.0, 112.0, 111.0, 109.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 123.0, 138.0, 196.0, 118.0, 136.0, 116.0, 149.0, 155.0, 117.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 186.0, 186.0, 184.0, 185.0, 183.0, 184.0, 185.0, 182.0, 182.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 59.0, 151.0, 79.0, 88.0, 57.0, 58.0, 110.0, 56.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 133.0, 81.0, 80.0, 76.0, 120.0, 113.0, 116.0, 106.0], "value": 100.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 83.0, 87.0, 83.0, 86.0, 84.0, 86.0, 83.0], "value": 84.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 93.0, 91.0, 92.0, 93.0, 94.0, 92.0, 92.0], "value": 92.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 84.0, 84.0, 86.0, 94.0, 134.0, 84.0, 83.0, 85.0, 86.0], "value": 89.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 56.0, 55.0, 55.0, 56.0, 55.0, 117.0, 56.0, 54.0], "value": 61.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 71.0, 74.0, 70.0, 69.0, 66.0, 66.0, 66.0, 67.0, 66.0], "value": 67.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 84.0, 85.0, 83.0, 84.0, 86.0, 85.0, 85.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 137.0, 138.0, 140.0, 150.0, 142.0, 151.0, 137.0, 237.0, 152.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 222.0, 144.0, 204.0, 221.0, 213.0, 164.0, 165.0, 162.0, 161.0], "value": 185.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 66.0, 67.0, 65.0, 65.0, 66.0, 64.0, 65.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1501.2, "alertThreshold": 2.0}]} 04:50:23 INFO - Return code: 0 04:50:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:50:23 ERROR - # TBPL SUCCESS # 04:50:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:50:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:50:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:50:23 INFO - 2016-05-19 04:50:23.869 screenresolution[1674:9592] starting screenresolution argv=screenresolution get 04:50:23 INFO - 2016-05-19 04:50:23.871 screenresolution[1674:9592] Display 0: 1600x1200x32@60 04:50:23 INFO - 2016-05-19 04:50:23.877 screenresolution[1675:9595] starting screenresolution argv=screenresolution list 04:50:23 INFO - Available Modes on Display 0 04:50:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:50:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:50:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:50:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:50:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:50:24 INFO - Intel Iris: 04:50:24 INFO - Chipset Model: Intel Iris 04:50:24 INFO - Type: GPU 04:50:24 INFO - Bus: Built-In 04:50:24 INFO - VRAM (Dynamic, Max): 1536 MB 04:50:24 INFO - Vendor: Intel (0x8086) 04:50:24 INFO - Device ID: 0x0a2e 04:50:24 INFO - Revision ID: 0x0009 04:50:24 INFO - Displays: 04:50:24 INFO - Display: 04:50:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:50:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:50:24 INFO - Display Serial Number: 1600x1200 60 04:50:24 INFO - Main Display: Yes 04:50:24 INFO - Mirror: Off 04:50:24 INFO - Online: Yes 04:50:24 INFO - Rotation: Supported 04:50:24 INFO - Return code: 0 04:50:24 INFO - Running post-action listener: _resource_record_post_action 04:50:24 INFO - ##### 04:50:24 INFO - ##### Finished run-tests step (success) 04:50:24 INFO - ##### 04:50:24 INFO - Running post-run listener: _resource_record_post_run 04:50:24 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37938688; Write bytes: 693992960; Read time: 426; Write time: 1941 04:50:24 INFO - TinderboxPrint: CPU usage
53.9% 04:50:24 INFO - TinderboxPrint: I/O read bytes / time
37,938,688 / 426 04:50:24 INFO - TinderboxPrint: I/O write bytes / time
693,992,960 / 1,941 04:50:24 INFO - TinderboxPrint: CPU idle
2,235.1 (46.1%) 04:50:24 INFO - TinderboxPrint: CPU system
968.2 (20.0%) 04:50:24 INFO - TinderboxPrint: CPU user
1,646.2 (33.9%) 04:50:24 INFO - TinderboxPrint: Swap in / out
433,451,008 / 0 04:50:24 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 379025920; Write bytes: 373221376; Read time: 28182; Write time: 683 04:50:24 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 36976128; Write bytes: 295794176; Read time: 408; Write time: 1207 04:50:24 INFO - Running post-run listener: _upload_blobber_files 04:50:24 INFO - Blob upload gear active. 04:50:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:50:24 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 04:50:24 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'] 04:50:24 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 04:50:24 INFO - (blobuploader) - INFO - Open directory for files ... 04:50:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:50:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:50:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:25 INFO - (blobuploader) - INFO - Done attempting. 04:50:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:50:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:50:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:26 INFO - (blobuploader) - INFO - Done attempting. 04:50:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:50:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:50:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:28 INFO - (blobuploader) - INFO - Done attempting. 04:50:28 INFO - (blobuploader) - INFO - Iteration through files over. 04:50:28 INFO - Return code: 0 04:50:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:50:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:50:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cfe566cb4f1f5afc4b06a40b45fa6aac82fbf2bd1cf6471bf494db91a7b29b4f6626dc5f2d42a08d5833ffc50e8275519cf1110041b9191fdd6519772808c11d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84481a9769451ed743f2f90a0b4efae9f28be16341f6b2147f7cb61db4afd5b605a8f4ec9d1b916ff82648c955098b320f7bda85b5f8cb0572a8b26ae6b1acb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dcaf6c1996fb77fe5c2505c8035eec346d00a218eba2d0d1bd700416da88a2360c98158d0961213953e863b15668bbf9c00374731673ef76a35f2b8d86594055"} 04:50:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:50:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:50:28 INFO - Contents: 04:50:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cfe566cb4f1f5afc4b06a40b45fa6aac82fbf2bd1cf6471bf494db91a7b29b4f6626dc5f2d42a08d5833ffc50e8275519cf1110041b9191fdd6519772808c11d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84481a9769451ed743f2f90a0b4efae9f28be16341f6b2147f7cb61db4afd5b605a8f4ec9d1b916ff82648c955098b320f7bda85b5f8cb0572a8b26ae6b1acb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dcaf6c1996fb77fe5c2505c8035eec346d00a218eba2d0d1bd700416da88a2360c98158d0961213953e863b15668bbf9c00374731673ef76a35f2b8d86594055"} 04:50:28 INFO - Running post-run listener: copy_logs_to_upload_dir 04:50:28 INFO - Copying logs to upload dir... 04:50:28 INFO - mkdir: /builds/slave/test/build/upload/logs 04:50:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.540674 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-19 04:50:29.018855) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 04:50:29.022814) ========= 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.vPBq9aPgrB/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.LGJX3t6brS/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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/cfe566cb4f1f5afc4b06a40b45fa6aac82fbf2bd1cf6471bf494db91a7b29b4f6626dc5f2d42a08d5833ffc50e8275519cf1110041b9191fdd6519772808c11d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84481a9769451ed743f2f90a0b4efae9f28be16341f6b2147f7cb61db4afd5b605a8f4ec9d1b916ff82648c955098b320f7bda85b5f8cb0572a8b26ae6b1acb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dcaf6c1996fb77fe5c2505c8035eec346d00a218eba2d0d1bd700416da88a2360c98158d0961213953e863b15668bbf9c00374731673ef76a35f2b8d86594055"} build_url:https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011166 build_url: 'https://queue.taskcluster.net/v1/task/T5pC2G6iSKa5ZKLPTpKXgA/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/cfe566cb4f1f5afc4b06a40b45fa6aac82fbf2bd1cf6471bf494db91a7b29b4f6626dc5f2d42a08d5833ffc50e8275519cf1110041b9191fdd6519772808c11d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84481a9769451ed743f2f90a0b4efae9f28be16341f6b2147f7cb61db4afd5b605a8f4ec9d1b916ff82648c955098b320f7bda85b5f8cb0572a8b26ae6b1acb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dcaf6c1996fb77fe5c2505c8035eec346d00a218eba2d0d1bd700416da88a2360c98158d0961213953e863b15668bbf9c00374731673ef76a35f2b8d86594055"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 04:50:29.051051) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 04:50:29.051368) ========= 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.vPBq9aPgrB/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.LGJX3t6brS/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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.004789 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 04:50:29.099379) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 04:50:29.099732) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 04:50:29.100036) ========= ========= Total master_lag: 0.15 =========