builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0387 starttime: 1464578173.56 results: success (0) buildid: 20160529191945 builduid: b6ab4b49b47e40b9aba09230e2d6b98b revision: e0354aa795ba11ec139cbe286e7217ba4fd0ad3e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-29 20:16:13.557212) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-29 20:16:13.557648) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-29 20:16:13.557932) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-29 20:16:13.773188) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 20:16:13.773784) ========= 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.wSdJiKNFBu/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.7bXbA5SHk8/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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-29 20:16:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 143M=0s 2016-05-29 20:16:14 (143 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.520652 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 20:16:14.326210) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 20:16:14.326495) ========= 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.wSdJiKNFBu/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.7bXbA5SHk8/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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.025686 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 20:16:14.411178) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-29 20:16:14.411522) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e0354aa795ba11ec139cbe286e7217ba4fd0ad3e --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 e0354aa795ba11ec139cbe286e7217ba4fd0ad3e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wSdJiKNFBu/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.7bXbA5SHk8/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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-29 20:16:14,519 truncating revision to first 12 chars 2016-05-29 20:16:14,519 Setting DEBUG logging. 2016-05-29 20:16:14,519 attempt 1/10 2016-05-29 20:16:14,519 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e0354aa795ba?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-29 20:16:15,640 unpacking tar archive at: fx-team-e0354aa795ba/testing/mozharness/ program finished with exit code 0 elapsedTime=1.383020 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-29 20:16:15.841136) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-29 20:16:15.841413) ========= script_repo_revision: e0354aa795ba11ec139cbe286e7217ba4fd0ad3e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-29 20:16:15.841820) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-29 20:16:15.842082) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-29 20:16:15.868179) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-05-29 20:16:15.868453) ========= /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.wSdJiKNFBu/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.7bXbA5SHk8/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 20:16:16 INFO - MultiFileLogger online at 20160529 20:16:16 in /builds/slave/test 20:16:16 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 20:16:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:16:16 INFO - {'append_to_log': False, 20:16:16 INFO - 'base_work_dir': '/builds/slave/test', 20:16:16 INFO - 'blob_upload_branch': 'Fx-Team', 20:16:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:16:16 INFO - 'branch': 'Fx-Team', 20:16:16 INFO - 'buildbot_json_path': 'buildprops.json', 20:16:16 INFO - 'config_files': ('talos/mac_config.py',), 20:16:16 INFO - 'default_actions': ('clobber', 20:16:16 INFO - 'read-buildbot-config', 20:16:16 INFO - 'download-and-extract', 20:16:16 INFO - 'populate-webroot', 20:16:16 INFO - 'create-virtualenv', 20:16:16 INFO - 'install', 20:16:16 INFO - 'run-tests'), 20:16:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:16:16 INFO - 'download_minidump_stackwalk': True, 20:16:16 INFO - 'download_symbols': 'ondemand', 20:16:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:16:16 INFO - 'tooltool.py': '/tools/tooltool.py', 20:16:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:16:16 INFO - '/tools/misc-python/virtualenv.py')}, 20:16:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:16:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:16:16 INFO - 'installer_path': 'installer.exe', 20:16:16 INFO - 'log_level': 'info', 20:16:16 INFO - 'log_name': 'talos', 20:16:16 INFO - 'log_to_console': True, 20:16:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:16:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:16:16 INFO - 'opt_config_files': (), 20:16:16 INFO - 'pip_index': False, 20:16:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:16:16 INFO - 'cmd': ('bash', 20:16:16 INFO - '-c', 20:16:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:16:16 INFO - 'enabled': True, 20:16:16 INFO - 'halt_on_failure': False, 20:16:16 INFO - 'name': 'check_screen_resolution'},), 20:16:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:16:16 INFO - 'cmd': ('bash', 20:16:16 INFO - '-c', 20:16:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:16:16 INFO - 'enabled': True, 20:16:16 INFO - 'halt_on_failure': False, 20:16:16 INFO - 'name': 'check_screen_resolution'},), 20:16:16 INFO - 'run_cmd_checks_enabled': True, 20:16:16 INFO - 'sps_profile': False, 20:16:16 INFO - 'sps_profile_interval': 0, 20:16:16 INFO - 'suite': 'dromaeojs', 20:16:16 INFO - 'system_bits': '32', 20:16:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:16:16 INFO - 'title': 't-yosemite-r7-0387', 20:16:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:16:16 INFO - 'use_talos_json': True, 20:16:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:16:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:16:16 INFO - 'work_dir': 'build'} 20:16:16 INFO - ##### 20:16:16 INFO - ##### Running clobber step. 20:16:16 INFO - ##### 20:16:16 INFO - Running pre-action listener: _resource_record_pre_action 20:16:16 INFO - Running main action method: clobber 20:16:16 INFO - rmtree: /builds/slave/test/build 20:16:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:16:17 INFO - Running post-action listener: _resource_record_post_action 20:16:17 INFO - ##### 20:16:17 INFO - ##### Finished clobber step (success) 20:16:17 INFO - ##### 20:16:17 INFO - ##### 20:16:17 INFO - ##### Running read-buildbot-config step. 20:16:17 INFO - ##### 20:16:17 INFO - Running pre-action listener: _resource_record_pre_action 20:16:17 INFO - Running main action method: read_buildbot_config 20:16:17 INFO - Using buildbot properties: 20:16:17 INFO - { 20:16:17 INFO - "project": "", 20:16:17 INFO - "product": "firefox", 20:16:17 INFO - "script_repo_revision": "production", 20:16:17 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 20:16:17 INFO - "repository": "", 20:16:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 20:16:17 INFO - "buildid": "20160529191945", 20:16:17 INFO - "pgo_build": "False", 20:16:17 INFO - "basedir": "/builds/slave/test", 20:16:17 INFO - "buildnumber": 726, 20:16:17 INFO - "slavename": "t-yosemite-r7-0387", 20:16:17 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 20:16:17 INFO - "platform": "yosemite_r7", 20:16:17 INFO - "scheduler": "tests-fx-team-macosx64-talos", 20:16:17 INFO - "branch": "fx-team", 20:16:17 INFO - "repo_path": "integration/fx-team", 20:16:17 INFO - "revision": "e0354aa795ba11ec139cbe286e7217ba4fd0ad3e", 20:16:17 INFO - "stage_platform": "macosx64", 20:16:17 INFO - "builduid": "b6ab4b49b47e40b9aba09230e2d6b98b", 20:16:17 INFO - "slavebuilddir": "test" 20:16:17 INFO - } 20:16:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 20:16:17 INFO - Running post-action listener: _resource_record_post_action 20:16:17 INFO - ##### 20:16:17 INFO - ##### Finished read-buildbot-config step (success) 20:16:17 INFO - ##### 20:16:17 INFO - ##### 20:16:17 INFO - ##### Running download-and-extract step. 20:16:17 INFO - ##### 20:16:17 INFO - Running pre-action listener: _resource_record_pre_action 20:16:17 INFO - Running main action method: download_and_extract 20:16:17 INFO - mkdir: /builds/slave/test/build/tests 20:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:16:17 INFO - https://queue.taskcluster.net/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 20:16:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 20:16:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 20:16:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/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 20:16:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/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 20:16:32 INFO - Downloaded 1199 bytes. 20:16:32 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 20:16:32 INFO - Using the following test package requirements: 20:16:32 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 20:16:32 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:16:32 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 20:16:32 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:16:32 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 20:16:32 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:16:32 INFO - u'jsshell-mac.zip'], 20:16:32 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:16:32 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 20:16:32 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 20:16:32 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:16:32 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 20:16:32 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:16:32 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 20:16:32 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:16:32 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 20:16:32 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:16:32 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 20:16:32 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:16: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')]} 20:16:32 INFO - https://queue.taskcluster.net/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:16:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:16:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:16:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/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 20:16:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/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 20:16:34 INFO - Downloaded 18684681 bytes. 20:16:34 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'] 20:16:34 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 20:16:37 INFO - Return code: 0 20:16:37 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 20:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:16:37 INFO - https://queue.taskcluster.net/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:16:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:16:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:16:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/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 20:16:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/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 20:16:44 INFO - Downloaded 18684681 bytes. 20:16:44 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'] 20:16:44 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 20:16:47 INFO - Return code: 0 20:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:16:47 INFO - https://queue.taskcluster.net/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 20:16:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:16:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:16:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/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 20:16:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/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 20:16:49 INFO - Downloaded 10834034 bytes. 20:16:49 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'] 20:16:49 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 20:16:49 INFO - Return code: 0 20:16:49 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')]} 20:16:49 INFO - https://queue.taskcluster.net/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 20:16:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:16:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:16:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 20:16:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P8FDQ398Q6qyIlBvCE4osw/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 20:16:52 INFO - Downloaded 110464889 bytes. 20:16:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:16:52 INFO - mkdir: /builds/slave/test/properties 20:16:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:16:52 INFO - Writing to file /builds/slave/test/properties/build_url 20:16:52 INFO - Contents: 20:16:52 INFO - build_url:https://queue.taskcluster.net/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:16:53 INFO - Running post-action listener: _resource_record_post_action 20:16:53 INFO - Running post-action listener: set_extra_try_arguments 20:16:53 INFO - ##### 20:16:53 INFO - ##### Finished download-and-extract step (success) 20:16:53 INFO - ##### 20:16:53 INFO - ##### 20:16:53 INFO - ##### Running populate-webroot step. 20:16:53 INFO - ##### 20:16:53 INFO - Running pre-action listener: _resource_record_pre_action 20:16:53 INFO - Running main action method: populate_webroot 20:16:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:16:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:16:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:16:53 INFO - '--tppagecycles', 20:16:53 INFO - '7'], 20:16:53 INFO - 'tests': ['tsvgm']}}, 20:16:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:16:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:16:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:16:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:16:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:16:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:16:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:53 INFO - 'talos_options': ['--disable-e10s'], 20:16:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:16:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:16:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:53 INFO - 'talos_options': ['--disable-e10s'], 20:16:53 INFO - 'tests': ['damp', 'tps']}, 20:16:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:53 INFO - 'tests': ['damp', 'tps']}, 20:16:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:16:53 INFO - 'tests': ['dromaeo_dom']}, 20:16:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:16:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:16:53 INFO - 'tests': ['a11yr', 20:16:53 INFO - 'ts_paint', 20:16:53 INFO - 'tpaint', 20:16:53 INFO - 'sessionrestore', 20:16:53 INFO - 'sessionrestore_no_auto_restore', 20:16:53 INFO - 'tabpaint']}, 20:16:53 INFO - 'other-e10s': {'tests': ['a11yr', 20:16:53 INFO - 'ts_paint', 20:16:53 INFO - 'tpaint', 20:16:53 INFO - 'sessionrestore', 20:16:53 INFO - 'sessionrestore_no_auto_restore', 20:16:53 INFO - 'tabpaint']}, 20:16:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:16:53 INFO - 'ts_paint', 20:16:53 INFO - 'tpaint', 20:16:53 INFO - 'sessionrestore', 20:16:53 INFO - 'sessionrestore_no_auto_restore', 20:16:53 INFO - 'tabpaint']}, 20:16:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:16:53 INFO - 'ts_paint', 20:16:53 INFO - 'tpaint', 20:16:53 INFO - 'sessionrestore', 20:16:53 INFO - 'sessionrestore_no_auto_restore', 20:16:53 INFO - 'tabpaint']}, 20:16:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:16:53 INFO - 'tests': ['a11yr', 20:16:53 INFO - 'ts_paint', 20:16:53 INFO - 'tpaint', 20:16:53 INFO - 'sessionrestore', 20:16:53 INFO - 'sessionrestore_no_auto_restore', 20:16:53 INFO - 'tabpaint']}, 20:16:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:16:53 INFO - 'tests': ['a11yr', 20:16:53 INFO - 'ts_paint', 20:16:53 INFO - 'tpaint', 20:16:53 INFO - 'sessionrestore', 20:16:53 INFO - 'sessionrestore_no_auto_restore', 20:16:53 INFO - 'tabpaint']}, 20:16:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:16:53 INFO - 'tests': ['tsvgx', 20:16:53 INFO - 'tsvgr_opacity', 20:16:53 INFO - 'tart', 20:16:53 INFO - 'tscrollx', 20:16:53 INFO - 'cart']}, 20:16:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:16:53 INFO - 'tsvgr_opacity', 20:16:53 INFO - 'tart', 20:16:53 INFO - 'tscrollx', 20:16:53 INFO - 'cart']}, 20:16:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:53 INFO - 'talos_options': ['--disable-e10s'], 20:16:53 INFO - 'tests': ['tp5o']}, 20:16:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:53 INFO - 'tests': ['tp5o']}, 20:16:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:53 INFO - 'talos_options': ['--disable-e10s', 20:16:53 INFO - '--xperf_path', 20:16:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:16:53 INFO - 'tests': ['tp5n']}, 20:16:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:16:53 INFO - 'talos_options': ['--xperf_path', 20:16:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:16:53 INFO - 'tests': ['tp5n']}}, 20:16:53 INFO - 'talos.zip': {'path': '', 20:16:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:16:53 INFO - Running post-action listener: _resource_record_post_action 20:16:53 INFO - ##### 20:16:53 INFO - ##### Finished populate-webroot step (success) 20:16:53 INFO - ##### 20:16:53 INFO - ##### 20:16:53 INFO - ##### Running create-virtualenv step. 20:16:53 INFO - ##### 20:16:53 INFO - Running pre-action listener: _resource_record_pre_action 20:16:53 INFO - Running main action method: create_virtualenv 20:16:53 INFO - Creating virtualenv /builds/slave/test/build/venv 20:16:53 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 20:16:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:16:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:16:53 INFO - Using real prefix '/tools/python27' 20:16:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:16:54 INFO - Installing distribute.............................................................................................................................................................................................done. 20:16:57 INFO - Installing pip.................done. 20:16:57 INFO - Return code: 0 20:16:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:16: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')]} 20:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16: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')]} 20:16:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:57 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 0x1089e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5e04a8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10888dd30>, '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 0x1086b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108884b20>, '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.wSdJiKNFBu/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.7bXbA5SHk8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 20:16:57 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 20:16:57 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 20:16:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSdJiKNFBu/Render', 20:16:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:16:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:16:57 INFO - 'HOME': '/Users/cltbld', 20:16:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:16:57 INFO - 'LOGNAME': 'cltbld', 20:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:57 INFO - 'MOZ_NO_REMOTE': '1', 20:16:57 INFO - 'NO_EM_RESTART': '1', 20:16:57 INFO - 'PAGER': '/bin/cat', 20:16:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:57 INFO - 'PWD': '/builds/slave/test', 20:16:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:16:57 INFO - 'SHELL': '/bin/bash', 20:16:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bXbA5SHk8/Listeners', 20:16:57 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 20:16:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:16:57 INFO - 'USER': 'cltbld', 20:16:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:16:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:57 INFO - 'XPC_FLAGS': '0x0', 20:16:57 INFO - 'XPC_SERVICE_NAME': '0', 20:16:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:16:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:57 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 20:16:57 INFO - Cleaning up... 20:16:57 INFO - Return code: 0 20:16:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:16: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')]} 20:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:16:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16: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')]} 20:16:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:16:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:16:57 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 0x1089e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5e04a8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10888dd30>, '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 0x1086b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108884b20>, '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.wSdJiKNFBu/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.7bXbA5SHk8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 20:16:57 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 20:16:57 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 20:16:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSdJiKNFBu/Render', 20:16:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:16:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:16:57 INFO - 'HOME': '/Users/cltbld', 20:16:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:16:57 INFO - 'LOGNAME': 'cltbld', 20:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:57 INFO - 'MOZ_NO_REMOTE': '1', 20:16:57 INFO - 'NO_EM_RESTART': '1', 20:16:57 INFO - 'PAGER': '/bin/cat', 20:16:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:57 INFO - 'PWD': '/builds/slave/test', 20:16:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:16:57 INFO - 'SHELL': '/bin/bash', 20:16:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bXbA5SHk8/Listeners', 20:16:57 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 20:16:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:16:57 INFO - 'USER': 'cltbld', 20:16:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:16:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:57 INFO - 'XPC_FLAGS': '0x0', 20:16:57 INFO - 'XPC_SERVICE_NAME': '0', 20:16:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:16:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:16:58 INFO - Downloading/unpacking psutil>=3.1.1 20:16:58 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 20:16:58 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 20:16:58 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 20:16:58 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 20:16:58 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 20:16:58 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 20:17:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:17:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:17:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:17:01 INFO - Installing collected packages: psutil 20:17:01 INFO - Running setup.py install for psutil 20:17:01 INFO - building 'psutil._psutil_osx' extension 20:17:01 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 20:17:02 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 20:17:02 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 20:17:02 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 20:17:02 INFO - building 'psutil._psutil_posix' extension 20:17:02 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 20:17:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:17:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:17:02 INFO - ^ 20:17:02 INFO - 1 warning generated. 20:17:02 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 20:17:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:17:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:17:02 INFO - Successfully installed psutil 20:17:02 INFO - Cleaning up... 20:17:02 INFO - Return code: 0 20:17:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:17: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')]} 20:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17: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')]} 20:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1089e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5e04a8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10888dd30>, '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 0x1086b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108884b20>, '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.wSdJiKNFBu/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.7bXbA5SHk8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 20:17:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:17:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSdJiKNFBu/Render', 20:17:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:02 INFO - 'HOME': '/Users/cltbld', 20:17:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:02 INFO - 'LOGNAME': 'cltbld', 20:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:02 INFO - 'MOZ_NO_REMOTE': '1', 20:17:02 INFO - 'NO_EM_RESTART': '1', 20:17:02 INFO - 'PAGER': '/bin/cat', 20:17:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:02 INFO - 'PWD': '/builds/slave/test', 20:17:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:02 INFO - 'SHELL': '/bin/bash', 20:17:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bXbA5SHk8/Listeners', 20:17:02 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 20:17:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:02 INFO - 'USER': 'cltbld', 20:17:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:02 INFO - 'XPC_FLAGS': '0x0', 20:17:02 INFO - 'XPC_SERVICE_NAME': '0', 20:17:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:17:02 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 20:17:02 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 20:17:02 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 20:17:02 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 20:17:02 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 20:17:02 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 20:17:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:17:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:17:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:17:05 INFO - Installing collected packages: mozsystemmonitor 20:17:05 INFO - Running setup.py install for mozsystemmonitor 20:17:05 INFO - Successfully installed mozsystemmonitor 20:17:05 INFO - Cleaning up... 20:17:05 INFO - Return code: 0 20:17:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:17:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1089e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5e04a8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10888dd30>, '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 0x1086b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108884b20>, '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.wSdJiKNFBu/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.7bXbA5SHk8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 20:17:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:17:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:17:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSdJiKNFBu/Render', 20:17:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:05 INFO - 'HOME': '/Users/cltbld', 20:17:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:05 INFO - 'LOGNAME': 'cltbld', 20:17:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:05 INFO - 'MOZ_NO_REMOTE': '1', 20:17:05 INFO - 'NO_EM_RESTART': '1', 20:17:05 INFO - 'PAGER': '/bin/cat', 20:17:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:05 INFO - 'PWD': '/builds/slave/test', 20:17:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:05 INFO - 'SHELL': '/bin/bash', 20:17:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bXbA5SHk8/Listeners', 20:17:05 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 20:17:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:05 INFO - 'USER': 'cltbld', 20:17:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:05 INFO - 'XPC_FLAGS': '0x0', 20:17:05 INFO - 'XPC_SERVICE_NAME': '0', 20:17:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:05 INFO - Downloading/unpacking blobuploader==1.2.4 20:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:08 INFO - Downloading blobuploader-1.2.4.tar.gz 20:17:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:17:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:17:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:09 INFO - Downloading docopt-0.6.1.tar.gz 20:17:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:17:09 INFO - Installing collected packages: blobuploader, requests, docopt 20:17:09 INFO - Running setup.py install for blobuploader 20:17:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:17:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:17:09 INFO - Running setup.py install for requests 20:17:10 INFO - Running setup.py install for docopt 20:17:10 INFO - Successfully installed blobuploader requests docopt 20:17:10 INFO - Cleaning up... 20:17:10 INFO - Return code: 0 20:17:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:17:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:10 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 0x1089e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5e04a8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10888dd30>, '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 0x1086b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108884b20>, '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.wSdJiKNFBu/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.7bXbA5SHk8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 20:17:10 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 20:17:10 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 20:17:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSdJiKNFBu/Render', 20:17:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:10 INFO - 'HOME': '/Users/cltbld', 20:17:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:10 INFO - 'LOGNAME': 'cltbld', 20:17:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:10 INFO - 'MOZ_NO_REMOTE': '1', 20:17:10 INFO - 'NO_EM_RESTART': '1', 20:17:10 INFO - 'PAGER': '/bin/cat', 20:17:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:10 INFO - 'PWD': '/builds/slave/test', 20:17:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:10 INFO - 'SHELL': '/bin/bash', 20:17:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bXbA5SHk8/Listeners', 20:17:10 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 20:17:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:10 INFO - 'USER': 'cltbld', 20:17:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:10 INFO - 'XPC_FLAGS': '0x0', 20:17:10 INFO - 'XPC_SERVICE_NAME': '0', 20:17:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:17:10 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-mlXOX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:17:10 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-S5N0Xa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:17:10 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-OFBH5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:17:10 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-jM2t47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:17:11 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-LiKQVe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:17:11 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-_wm_7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:17:11 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-VwqP_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:17:11 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-Pn8tS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:17:11 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-kLFKoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:17:11 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-JLEAN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:17:11 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-YYJZqh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:17:11 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-Pvrqtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:17:11 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-0rrwsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:17:11 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-3jiJX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:12 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-8bQYmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:17:12 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-asDx0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:17:12 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-L0GXyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:17:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:17:12 INFO - Running setup.py install for manifestparser 20:17:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:17:12 INFO - Running setup.py install for mozcrash 20:17:12 INFO - Running setup.py install for mozdebug 20:17:12 INFO - Running setup.py install for mozdevice 20:17:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:17:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:17:12 INFO - Running setup.py install for mozfile 20:17:13 INFO - Running setup.py install for mozhttpd 20:17:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:17:13 INFO - Running setup.py install for mozinfo 20:17:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:17:13 INFO - Running setup.py install for mozInstall 20:17:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:17:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:17:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:17:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:17:13 INFO - Running setup.py install for mozleak 20:17:13 INFO - Running setup.py install for mozlog 20:17:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:17:13 INFO - Running setup.py install for moznetwork 20:17:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:17:13 INFO - Running setup.py install for mozprocess 20:17:14 INFO - Running setup.py install for mozprofile 20:17:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:17:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:17:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:17:14 INFO - Running setup.py install for mozrunner 20:17:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:17:14 INFO - Running setup.py install for mozscreenshot 20:17:14 INFO - Running setup.py install for moztest 20:17:14 INFO - Running setup.py install for mozversion 20:17:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:17:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:17:14 INFO - Cleaning up... 20:17:14 INFO - Return code: 0 20:17:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:17:14 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')]} 20:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:14 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')]} 20:17:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:14 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 0x1089e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5e04a8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10888dd30>, '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 0x1086b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108884b20>, '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.wSdJiKNFBu/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.7bXbA5SHk8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 20:17:14 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 20:17:14 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 20:17:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSdJiKNFBu/Render', 20:17:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:14 INFO - 'HOME': '/Users/cltbld', 20:17:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:14 INFO - 'LOGNAME': 'cltbld', 20:17:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:14 INFO - 'MOZ_NO_REMOTE': '1', 20:17:14 INFO - 'NO_EM_RESTART': '1', 20:17:14 INFO - 'PAGER': '/bin/cat', 20:17:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:14 INFO - 'PWD': '/builds/slave/test', 20:17:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:14 INFO - 'SHELL': '/bin/bash', 20:17:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bXbA5SHk8/Listeners', 20:17:14 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 20:17:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:14 INFO - 'USER': 'cltbld', 20:17:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:14 INFO - 'XPC_FLAGS': '0x0', 20:17:14 INFO - 'XPC_SERVICE_NAME': '0', 20:17:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:17:15 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-NGXcMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:17:15 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)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:17:15 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-5TVWXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:17:15 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)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:17:15 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-xvDgPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:17:15 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)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:17:15 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-Mpbji4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:17:15 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)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:17:15 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-V02z4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:17:15 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)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:17:15 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-8AVzr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:17:15 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)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:17:15 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-vbd6rk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:17:15 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)) 20:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:17:15 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-4k9yZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:17:16 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)) 20:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:17:16 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-VTCZ4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:17:16 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)) 20:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:17:16 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-BnAlOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:17:16 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)) 20:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:17:16 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-LmMRMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:17:16 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)) 20:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:17:16 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-kZEeTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:17:16 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)) 20:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:17:16 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-saORP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:17:16 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)) 20:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:17:16 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-LjrUu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:17:16 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)) 20:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:16 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-_T3n_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:17:16 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)) 20:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:17:16 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-bcpbUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:17:16 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)) 20:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:17:16 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-UOXJnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:17:17 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)) 20:17:17 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)) 20:17:17 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)) 20:17:17 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)) 20:17:17 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)) 20:17:17 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)) 20:17:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:19 INFO - Downloading blessings-1.6.tar.gz 20:17:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:17:19 INFO - Installing collected packages: blessings 20:17:19 INFO - Running setup.py install for blessings 20:17:19 INFO - Successfully installed blessings 20:17:19 INFO - Cleaning up... 20:17:19 INFO - Return code: 0 20:17:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:17:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:17:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:17:20 INFO - Reading from file tmpfile_stdout 20:17:20 INFO - Current package versions: 20:17:20 INFO - blessings == 1.6 20:17:20 INFO - blobuploader == 1.2.4 20:17:20 INFO - docopt == 0.6.1 20:17:20 INFO - manifestparser == 1.1 20:17:20 INFO - mozInstall == 1.12 20:17:20 INFO - mozcrash == 0.17 20:17:20 INFO - mozdebug == 0.1 20:17:20 INFO - mozdevice == 0.48 20:17:20 INFO - mozfile == 1.2 20:17:20 INFO - mozhttpd == 0.7 20:17:20 INFO - mozinfo == 0.9 20:17:20 INFO - mozleak == 0.1 20:17:20 INFO - mozlog == 3.2 20:17:20 INFO - moznetwork == 0.27 20:17:20 INFO - mozprocess == 0.22 20:17:20 INFO - mozprofile == 0.28 20:17:20 INFO - mozrunner == 6.11 20:17:20 INFO - mozscreenshot == 0.1 20:17:20 INFO - mozsystemmonitor == 0.3 20:17:20 INFO - moztest == 0.7 20:17:20 INFO - mozversion == 1.4 20:17:20 INFO - psutil == 3.1.1 20:17:20 INFO - requests == 1.2.3 20:17:20 INFO - wsgiref == 0.1.2 20:17:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:17: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')]} 20:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17: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')]} 20:17:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:20 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 0x1089e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5e04a8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10888dd30>, '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 0x1086b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108884b20>, '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.wSdJiKNFBu/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.7bXbA5SHk8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 20:17:20 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 20:17:20 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 20:17:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSdJiKNFBu/Render', 20:17:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:20 INFO - 'HOME': '/Users/cltbld', 20:17:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:20 INFO - 'LOGNAME': 'cltbld', 20:17:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:20 INFO - 'MOZ_NO_REMOTE': '1', 20:17:20 INFO - 'NO_EM_RESTART': '1', 20:17:20 INFO - 'PAGER': '/bin/cat', 20:17:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:20 INFO - 'PWD': '/builds/slave/test', 20:17:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:20 INFO - 'SHELL': '/bin/bash', 20:17:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bXbA5SHk8/Listeners', 20:17:20 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 20:17:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:20 INFO - 'USER': 'cltbld', 20:17:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:20 INFO - 'XPC_FLAGS': '0x0', 20:17:20 INFO - 'XPC_SERVICE_NAME': '0', 20:17:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:20 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)) 20:17:20 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)) 20:17:20 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)) 20:17:20 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)) 20:17:20 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)) 20:17:20 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)) 20:17:20 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)) 20:17:20 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)) 20:17:20 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)) 20:17:20 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)) 20:17:20 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)) 20:17:20 INFO - Cleaning up... 20:17:20 INFO - Return code: 0 20:17:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:17: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')]} 20:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17: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')]} 20:17:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1089e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5e04a8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10888dd30>, '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 0x1086b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108884b20>, '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.wSdJiKNFBu/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.7bXbA5SHk8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 20:17:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:17:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:17:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSdJiKNFBu/Render', 20:17:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:20 INFO - 'HOME': '/Users/cltbld', 20:17:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:20 INFO - 'LOGNAME': 'cltbld', 20:17:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:20 INFO - 'MOZ_NO_REMOTE': '1', 20:17:20 INFO - 'NO_EM_RESTART': '1', 20:17:20 INFO - 'PAGER': '/bin/cat', 20:17:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:20 INFO - 'PWD': '/builds/slave/test', 20:17:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:20 INFO - 'SHELL': '/bin/bash', 20:17:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bXbA5SHk8/Listeners', 20:17:20 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 20:17:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:20 INFO - 'USER': 'cltbld', 20:17:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:20 INFO - 'XPC_FLAGS': '0x0', 20:17:20 INFO - 'XPC_SERVICE_NAME': '0', 20:17:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:20 INFO - Downloading/unpacking jsonschema 20:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:17:23 INFO - Downloading/unpacking functools32 (from jsonschema) 20:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:23 INFO - Downloading functools32-3.2.3-2.tar.gz 20:17:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:17:23 INFO - warning: no files found matching '*.txt' 20:17:23 INFO - no previously-included directories found matching 'build' 20:17:23 INFO - no previously-included directories found matching 'dist' 20:17:23 INFO - no previously-included directories found matching '.git*' 20:17:23 INFO - Installing collected packages: jsonschema, functools32 20:17:23 INFO - Running setup.py install for functools32 20:17:23 INFO - warning: no files found matching '*.txt' 20:17:23 INFO - no previously-included directories found matching 'build' 20:17:23 INFO - no previously-included directories found matching 'dist' 20:17:23 INFO - no previously-included directories found matching '.git*' 20:17:23 INFO - Successfully installed jsonschema functools32 20:17:23 INFO - Cleaning up... 20:17:24 INFO - Return code: 0 20:17:24 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:17:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:17:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:17:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:17:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1089e9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1089ef570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5e04a8cf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10888dd30>, '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 0x1086b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108884b20>, '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.wSdJiKNFBu/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.7bXbA5SHk8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 20:17:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 20:17:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 20:17:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSdJiKNFBu/Render', 20:17:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:17:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:17:24 INFO - 'HOME': '/Users/cltbld', 20:17:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:17:24 INFO - 'LOGNAME': 'cltbld', 20:17:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:17:24 INFO - 'MOZ_NO_REMOTE': '1', 20:17:24 INFO - 'NO_EM_RESTART': '1', 20:17:24 INFO - 'PAGER': '/bin/cat', 20:17:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:17:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:17:24 INFO - 'PWD': '/builds/slave/test', 20:17:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:17:24 INFO - 'SHELL': '/bin/bash', 20:17:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bXbA5SHk8/Listeners', 20:17:24 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 20:17:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:17:24 INFO - 'USER': 'cltbld', 20:17:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:17:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:17:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:17:24 INFO - 'XPC_FLAGS': '0x0', 20:17:24 INFO - 'XPC_SERVICE_NAME': '0', 20:17:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:17:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:17:24 INFO - Downloading/unpacking flake8 20:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:26 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:17:26 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:17:26 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 20:17:26 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 20:17:26 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 20:17:26 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 20:17:26 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 20:17:26 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 20:17:27 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:17:27 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:27 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:17:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:17:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:17:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:17:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:17:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:17:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:17:28 INFO - no previously-included directories found matching 'docs/_build' 20:17:28 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:17:28 INFO - Running setup.py install for pep8 20:17:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:17:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:17:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:17:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:17:28 INFO - no previously-included directories found matching 'docs/_build' 20:17:28 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:17:28 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:17:28 INFO - Cleaning up... 20:17:28 INFO - Return code: 0 20:17:28 INFO - Running post-action listener: _resource_record_post_action 20:17:28 INFO - Running post-action listener: _start_resource_monitoring 20:17:28 INFO - Starting resource monitoring. 20:17:28 INFO - ##### 20:17:28 INFO - ##### Finished create-virtualenv step (success) 20:17:28 INFO - ##### 20:17:28 INFO - ##### 20:17:28 INFO - ##### Running install step. 20:17:28 INFO - ##### 20:17:28 INFO - Running pre-action listener: _resource_record_pre_action 20:17:28 INFO - Running main action method: install 20:17:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:17:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:17:28 INFO - Reading from file tmpfile_stdout 20:17:28 INFO - Detecting whether we're running mozinstall >=1.0... 20:17:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:17:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:17:28 INFO - Reading from file tmpfile_stdout 20:17:28 INFO - Output received: 20:17:28 INFO - Usage: mozinstall [options] installer 20:17:28 INFO - Options: 20:17:28 INFO - -h, --help show this help message and exit 20:17:28 INFO - -d DEST, --destination=DEST 20:17:28 INFO - Directory to install application into. [default: 20:17:28 INFO - "/builds/slave/test"] 20:17:28 INFO - --app=APP Application being installed. [default: firefox] 20:17:28 INFO - mkdir: /builds/slave/test/build/application 20:17:28 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'] 20:17:28 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 20:18:02 INFO - Reading from file tmpfile_stdout 20:18:02 INFO - Output received: 20:18:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:18:02 INFO - Running post-action listener: _resource_record_post_action 20:18:02 INFO - ##### 20:18:02 INFO - ##### Finished install step (success) 20:18:02 INFO - ##### 20:18:02 INFO - ##### 20:18:02 INFO - ##### Running run-tests step. 20:18:02 INFO - ##### 20:18:02 INFO - Running pre-action listener: _resource_record_pre_action 20:18:02 INFO - Running main action method: run_tests 20:18:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:18:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:18:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:18:02 INFO - 2016-05-29 20:18:02.603 screenresolution[1645:8655] starting screenresolution argv=screenresolution get 20:18:02 INFO - 2016-05-29 20:18:02.605 screenresolution[1645:8655] Display 0: 1600x1200x32@60 20:18:02 INFO - 2016-05-29 20:18:02.611 screenresolution[1646:8657] starting screenresolution argv=screenresolution list 20:18:02 INFO - Available Modes on Display 0 20:18:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:18:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:18:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:18:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:18:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:18:02 INFO - Intel Iris: 20:18:02 INFO - Chipset Model: Intel Iris 20:18:02 INFO - Type: GPU 20:18:02 INFO - Bus: Built-In 20:18:02 INFO - VRAM (Dynamic, Max): 1536 MB 20:18:02 INFO - Vendor: Intel (0x8086) 20:18:02 INFO - Device ID: 0x0a2e 20:18:02 INFO - Revision ID: 0x0009 20:18:02 INFO - Displays: 20:18:02 INFO - Display: 20:18:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:18:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:18:02 INFO - Display Serial Number: 1600x1200 60 20:18:02 INFO - Main Display: Yes 20:18:02 INFO - Mirror: Off 20:18:02 INFO - Online: Yes 20:18:02 INFO - Rotation: Supported 20:18:02 INFO - Return code: 0 20:18:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:18:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:18:02 INFO - Python 2.7.3 20:18:02 INFO - Return code: 0 20:18:02 INFO - grabbing minidump binary from tooltool 20:18: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')]} 20:18:02 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 0x10888dd30>, '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 0x1086b1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108884b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:18:02 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 20:18:02 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 20:18:02 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 20:18:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:18:02 INFO - Return code: 0 20:18:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:18:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:18:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:18:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:18:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:18:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:18:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:18:04 INFO - Return code: 0 20:18:04 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/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0387', '--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 20:18:04 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/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0387 --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 20:18:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wSdJiKNFBu/Render', 20:18:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:18:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:18:04 INFO - 'HOME': '/Users/cltbld', 20:18:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:18:04 INFO - 'LOGNAME': 'cltbld', 20:18:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:18:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:18:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:18:04 INFO - 'MOZ_NO_REMOTE': '1', 20:18:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:18:04 INFO - 'NO_EM_RESTART': '1', 20:18:04 INFO - 'PAGER': '/bin/cat', 20:18:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:18:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:18:04 INFO - 'PWD': '/builds/slave/test', 20:18:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:18:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:18:04 INFO - 'SHELL': '/bin/bash', 20:18:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7bXbA5SHk8/Listeners', 20:18:04 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 20:18:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:18:04 INFO - 'USER': 'cltbld', 20:18:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:18:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:18:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:18:04 INFO - 'XPC_FLAGS': '0x0', 20:18:04 INFO - 'XPC_SERVICE_NAME': '0', 20:18:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:18:04 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/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0387', '--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 20:18:04 INFO - mozversion application_buildid: 20160529191945 20:18:04 INFO - mozversion application_changeset: e0354aa795ba11ec139cbe286e7217ba4fd0ad3e 20:18:04 INFO - mozversion application_display_name: Nightly 20:18:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:18:04 INFO - mozversion application_name: Firefox 20:18:04 INFO - mozversion application_remotingname: firefox 20:18:04 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:18:04 INFO - mozversion application_vendor: Mozilla 20:18:04 INFO - mozversion application_version: 49.0a1 20:18:04 INFO - mozversion platform_buildid: 20160529191945 20:18:04 INFO - mozversion platform_changeset: e0354aa795ba11ec139cbe286e7217ba4fd0ad3e 20:18:04 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:18:04 INFO - mozversion platform_version: 49.0a1 20:18:04 INFO - using testdate: 1464578284 20:18:04 INFO - actual date: 1464578284 20:18:04 INFO - starting webserver on 'localhost:49275' 20:18:04 INFO - SUITE-START | Running 2 tests 20:18:04 INFO - TEST-START | dromaeo_css 20:18:04 INFO - operating with platform_type : mac_ 20:18:04 INFO - Initialising browser for dromaeo_css test... 20:18:04 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmpGJ80tc/profile http://localhost:49275/getInfo.html) 20:18:06 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 20:18:06 INFO - PROCESS | 1664 | colorDepth:24 20:18:06 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 20:18:06 INFO - PROCESS | 1664 | __metrics 20:18:06 INFO - PROCESS | 1664 | 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" 20:18:06 ERROR - PROCESS | 1664 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:18:07 INFO - TEST-INFO | 1664: exit 0 20:18:07 INFO - Browser initialized. 20:18:07 INFO - Running cycle 1/1 for dromaeo_css test... 20:18:07 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmpGJ80tc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:18:19 INFO - PROCESS | 1667 | RSS: Main: 241659904 20:18:19 INFO - PROCESS | 1667 | 20:19:10 INFO - PROCESS | 1667 | 1464578350295 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] 20:21:21 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 20:21:23 INFO - PROCESS | 1667 | RSS: Main: 328740864 20:21:23 INFO - PROCESS | 1667 | 20:24:26 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 20:24:26 INFO - PROCESS | 1667 | RSS: Main: 380370944 20:24:26 INFO - PROCESS | 1667 | 20:27:28 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 20:27:29 INFO - PROCESS | 1667 | RSS: Main: 374169600 20:27:29 INFO - PROCESS | 1667 | 20:30:31 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 20:30:32 INFO - PROCESS | 1667 | RSS: Main: 372715520 20:30:32 INFO - PROCESS | 1667 | 20:33:35 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 20:33:36 INFO - PROCESS | 1667 | RSS: Main: 339963904 20:33:36 INFO - PROCESS | 1667 | 20:36:39 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 20:36:39 INFO - PROCESS | 1667 | RSS: Main: 382140416 20:36:39 INFO - PROCESS | 1667 | 20:36:39 INFO - PROCESS | 1667 | __start_tp_report 20:36:39 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 20:36:39 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 20:36:39 INFO - PROCESS | 1667 | |i|pagename|runs| 20:36:39 INFO - PROCESS | 1667 | |0;dojo.html;3466.6013408458034;3468.1156305142185;3481.8781342653006;3499.440089585667;3539.1682954505704;5599.300087489064;5634.549236061115;5717.828465146053;5754.856128596787;5763.0779075347955;4830.396200474941;5666.291713535808;5758.942410575893;5766.481016708495;5780.797672081478;5208.270842082112;5243.239730239113;5268.130758424855;5277.7888884444765;5328.413874473824;5280.841574752763;5307.203919412081;5319.946800531984;5323.068463018975;5324.840254792362;5190.71451070191;5204.635675502716;5204.661696989683;5226.503482169188;5312.654677445954;5304.840854774363;5336.946630533684;5342.439043900412;5352.866178345534;5356.558312088955;5122.948770512322;5122.974385128088;5130.333056702642;5227.738613069331;5233.058049551079;5521.779128834842;5522.779088836442;5549.667019978814;5567.137093750434;5600.4959553640365;6264.906026409608;6269.153664255313;6283.5287353448675;6301.968490157566;6322.715477803464;6116.482126224659;6235.127082208494;6314.62112273265;6319.052142178664;6328.841778955517;5907.202527658797;5911.733971971272;5931.73307201177;5938.168656388109;5941.287045554474;6258.374162583706;6274.964630835952;6285.9057114143325;6294.905576416358;6297.622142671455;6046.606970546901;6106.297775755756;6121.056693971993;6129.297663865929;6187.81436556904;6273.153124328157;6289.496840252768;6300.779472718433;6303.36966303366;6306.369363063749;6155.907661385083;6215.720292586844;6230.56386052981;6235.469985051231;6238.407351301619;6143.938560614337;6148.3399482139575;6151.046587778901;6213.471854892295;6233.812985610348;4942.332785073969;4971.726555039508;4973.90052198954;4977.925331120036;5008.098542262355;4503.256962601135;4507.5267096955;4513.232750432434;4515.277555591156;4544.477385100756;4958.752062396866;5605.915911261334;5666.34836993751;5694.345150307768;5721.828345149652;6228.552415289876;6260.405261500241;6280.026595877646;6295.685215739195;6309.179806625201;5719.799807006818;5727.398623144593;5745.1382292656435;5745.482906538432;5769.798057067983;5305.442928492453;5352.6253162279045;5398.082326004589;5419.945800542023;5422.728863556807;6261.906071409024;6306.400891915261;6316.494680425647;6320.810375688737;6340.556161068773;6127.922628246602;6210.285817131088;6314.210723659543;6331.176946996861;6338.080978258219;6225.719842607003;6232.968835155795;6262.15460912771;6342.39747224013;6362.109304697438;6206.937930620636;6237.033259844731;6245.28179259391;6246.937530624818;6249.156363890998;6108.433136212153;6157.692115394123;6306.810795676137;6307.873842523216;6324.778632747832;5997.670128142994;6064.696765161812;6113.816585502442;6132.753252973555;6142.744345496893;6078.7264573093435;6131;6164.3835616438;6167.444929956326;6169.074638804215;5738.48353648157;5834.212381328636;5883.411658834082;5979.760809567569;5987.251593550806;5947.6431414115295;6031.969840150771;6042.637441753333;6063.969680151571;6105.297890742621;5877.324107727666;5992.101184822312;5993.31076926159;6010.459058684739;6016.157737916607 20:36:39 INFO - PROCESS | 1667 | |1;ext.html;497.2119191082134;526.9657472264305;532.9360476742792;538.1550964984975;538.2518299563608;28934.85532572337;42107.94730131744;43119;43234.78382608091;45551.77224113883;44859.77570112153;45183;45316.54683453165;45321.54678453215;45357;8716.215540601344;9483.146516813467;9701.126898579127;9708;9716.562754676057;8688.56557172142;11787.528498860036;12140.757184856298;12163.878361216362;12169;11927.642170734849;12014.879851201507;12021.278723276631;12025;12042.337671428068;8683.826323473559;11615.477303521362;11866.347350895698;11905.047596192284;11992.460339284753;11865.169438139334;11967.281963082243;11970.34163121028;11984.28094314344;11984.28094314344;41259;41728.37407438891;41734;41791.791041044904;41885.371719424234;3507.386207413718;3521.154922818505;3638.7634803737674;3838.827252773604;4247.511536173349;31664.525032124307;32345.353092938243;32365.6763432365;32637.184070398194;33071.834640826644;3257.1857035741064;3283.4910588858766;3303.966960330414;3318.983405082983;3430.125318043883;501806.4909675465;503863.4806825979;505300;505621;505762;34673.826630866686;61328.69335653293;61783.073253901224;61935.380646193866;62031.06953395703;35487.467687984186;36365.5453781851;36481.27037459236;36516.634833651326;36616.45075323872;12551.372431378395;14954.476593319181;15102.848971510364;15111.773323400157;15138.394464221528;9788.314817962817;10090.747731306776;11651.883481165249;11999.1600587958;12773.489060437665;7522.435817313723;7898.565578893216;7998.760037198894;7998.800029999296;8184.549849758201;3277.5083737439577;3319.170207448138;3322.235885746292;3330.700236978635;3347.8660853565584;993.5529011944653;1027.0551092994315;1059.4543809937888;1066.1097983183972;1145.061049939042;30606.54090188649;30723.078307650805;30969.845150774552;31408.37183256322;31543.84228078845;1089.7112265249716;1142.7600203957263;1158.3281696615943;1179.9115066369884;1197.329495482515;15815.130167840649;16087.95428297157;16113;16236.420162783508;16326.489804692048;702824;709636;710911;711616;713113.4344328245;59813.80372392528;61496.69251653713;61610.76778464495;61644.38355616386;61657.07514387288;1302.4920281090383;1345.239939434236;1422.217780220895;1433.7132573485344;1442.7114577084415;30751.2312192197;31742.36515269728;32064.35871282608;32585.837070815443;32742;50899;51189.74405127951;51428.97142057063;51446.742766285926;51498.74250628723;563765.2682156105;563983;569002;572152;574829;24333.27000189997;26650.46699065969;27159.456810864067;27208.86395568089;27981.86009070023;48518.272225915935;48622;49035;50128.749356252985;50471.49528504668;39926.201475969734;40258.99352516211;40418.79790601146;40645.79677101596;40861.38707919324;39484.60515394809;40133.1973360537;40336;40680;41077.97305067278;38276.80861595674;38882;39222;39290.2141957165;39345.60654393419;33915.830420847735;34570.308593828486;35465.64534354623;35596.466053009746;35611;21974.340769776933;24352.878235608707;25333;25837.870810645825;26450 20:36:39 INFO - PROCESS | 1667 | |2;jquery.html;355.64435564435564;379.06749396484645;385.7510712545857;401.92162528306994;407.090153506912;16216.594585135363;19409;20220;20675.37973860783;20728.378148655545;20538.589228215464;20761.896190519063;20990.580188396223;21873.343799686034;21915.561688766215;5266.236395722618;5529.949309631157;5632.098864181721;5817.7382017809305;5827.358990511061;5751.712414379285;5760.683162426065;5808.651480911138;5823.708814559276;5837.766489340422;5463.945360546403;5678.346990096151;5711.143328500717;5724.513416359594;5733.799317023905;5426;5681.460261275193;5710.314762228559;5714.828555143353;5717.914231286534;4964.131277026542;4995.275685025687;5002;5011.924821127686;5018.347614810052;24714.629280560806;25444.363890902692;25451.872740636365;25638.87180564104;25675.871620641963;2230.3754948614414;2240.8767517786514;2266.3767463947547;2279.4757205842748;2521.344450442898;15401.3839446422;15655.530334089995;15792.289346979298;15802;16171.35314587415;1758.9912050439793;1848.1313782522327;1872.4757068020976;1875.1936667233085;1955.2667749593902;83877.58061209654;108286;108552;109564;109570.35644465419;10422.478876056166;10489.370637761605;10874.510647020903;10902.400367979837;11032.28290161137;13647.727045459034;13710;13734.244616545986;13743.931280343535;13811.964102692138;8375.371847111492;8585.398852958942;8589.52757598338;8697.08680588529;8737.956310218407;8627.956860215785;8663.047064822864;8672.523011234443;8768.649254029768;8792.736217913473;5628.690422026746;6459.773907913294;6470.773522926675;6581.670916454158;6711.865762684719;1658.233980810433;1780.3946658136038;1823.1613457809292;1827.7167039108958;1831.6519861226325;821.7247222180637;952.4713783850029;982.1995074014656;1063.8776540697763;1072.2172813845937;20423.897880510503;21035.47411314699;21118.366449006553;21301.934903254776;21307.68038479424;965.9521813754744;1003.8494225866178;1097.4567589043475;1100.6367898593444;1154.6362895687832;11333.829992550118;11616.477258523386;11660.242084264666;11679.357635329969;11680.007199388147;582950;590116;592761;604958;606557;94011.52994234985;100555.49722251343;116359;116919;118131;942.5993952569993;981.2787601113176;998.2114129837347;1019.8113349030453;1020.3418794877263;15787;17486.300547978448;19277.036148192255;19277.325293614656;19603.607927841647;46840.53159468498;47944.76027619979;48043.51956480391;48196;48396.5160348392;63688.68155659192;63836.68081659748;64534.38664033249;64903.02645460227;65064;6880.58716477013;7710.845783084419;7836.373090152888;7870.527768333918;7903.091144518224;12215.877841221474;12410.317432541297;12472.376381181086;12633;12652.367381630698;10893.618723344645;10970.835437468284;11022.669319920415;11063.668089957315;11106.833397498882;10638.468076595984;10679.786404271716;10698.465076746288;10757.9462102689;10922.453877306261;10805;10998.450077496253;11096;11221.66335009951;11285.322880627189;6751.93248067513;7199.208087110518;7297.817554561178;7385.667644955883;7446.590437525988;10213.08082272599;10428.947855260674;10916.563337466412;11115.499802508744;11199.496022679163 20:36:39 INFO - PROCESS | 1667 | |3;mootools.html;1395.3581352577814;1536.4545586316858;1580.7470804671252;1809.3938530592252;1958.8041195880414;7753.844923101535;8589.699360522383;8756.12438756124;8786.560671966392;8917.420367676097;8561.48631082134;8768.167024132714;8772.210501054904;8774.47353158811;8786.86819697705;2331.300609817052;2364.18435639704;2365.4914193448403;2365.9053637854463;2367.088670861722;2336.754640762721;2371.905123795055;2376;2376.251480783548;2384.856908585482;2329.9417514562188;2350.3419042667997;2361.728401233854;2365.6042934668544;2374.988125059372;2314.5370925814736;2341.0518739910362;2341.5785158671433;2349.5535848188874;2354.587947109266;2308.607536718762;2336.1356298169617;2343.437574981992;2343.5781559319316;2345.566070277004;8489.533075680834;8649.264812490946;8716.084811094806;8741.256993155526;8742.781430464289;5197.402298735656;5399.514043736043;5405.5135037846785;5413.2692086568595;5414.133738601805;3523.5067090607336;3569.714422846192;3642.25333806569;3643.0892276930767;3647.854085836563;1066.6213494209599;1077.4128100185417;1083.3066837224183;1086.1582273737838;1101.8126918423886;11523.962843344056;11631.360275184777;11636.883631163664;11643.417829108595;11647.184697071209;4449.443819522559;4465.754383508938;4571.519990401027;4606.055758569485;4609.654275929319;9090.500022498825;9091.590878410487;9092;9093.772655683528;9098.999110097884;5192.9614077184715;5195.48045195485;5196.324477817935;5201.479852014844;5202.661826981234;4001.8399264029695;4412.227860124465;4563.361129441881;4785.545373189541;4862.41651001882;3738.3831667775057;3739.2895349883433;3807.676347510437;3839.500864887603;3865.594112618191;1009.9244304815348;1163.6567212672282;1172.6833754886134;1178.6051672689746;1178.9764204715857;646.3149061994301;774.7797219379387;847.9915200847912;936.8126374725082;968.5157421289356;5601.5238704710555;5833.620814647034;5867.413258674099;5884.735186916599;5891.23413956183;921.635953798246;926.0461724423855;930.6417029443713;945.9027528067546;981.6662334806057;3795.0322667719556;3837.76973381598;3920.2355540669864;3927.6465118139504;3954.7824869631872;10306.381617102998;10511;10690.78618427627;10718.892811071946;10721.624743133953;10482.213833962483;10626.043656070991;10642.627508037182;10658.786824263627;10682.679519614425;7897.960510197412;8011.078725946591;8090.231428014329;8184.590770461572;8204.384671149806;5636.915446268227;5678.119891416837;5694.857628559153;5707.6290041147195;5825.94174058271;9484.715458535979;9636.614535418505;9947.701568952654;9972.551235194578;9984.900150998688;8397.916020839713;9925.553350099119;10078.445685487368;10229.130523905254;10354.326968747007;6628.83427914306;6856.794296171123;6926.445884329342;6986.930130698831;6993.965030174817;7363.595002274712;7961.562114083781;8004.479708818908;8021.799455013671;8099.959500202462;7144.4641651877355;7303.488755787044;7367.115946086289;7395.704171833282;7414.888776668462;7116.679749411185;7223.494355395072;7224.13310402755;7341.889871651822;7371.336579707852;6893.103896493624;6938.306169383223;6975.162980442176;6977.092977912839;6994.5803251805055;4739.952600473951;4907.3620429343955;4963.801447942186;4974.154393753143;5011.32347133147;4939.975300123477;5006.147725341647;5029.1953287473825;5038.571721403721;5159.948400515947 20:36:39 INFO - PROCESS | 1667 | |4;prototype.html;303.1708277860051;310.83215063865515;319.28639490738203;321.53056537455313;323.4678953122114;4595.7242565446095;5100.846974590759;5262.526372626463;5324.467553244678;5342.65272757271;4615.353850460938;5005.399352077747;5108.463611320804;5121.692698438096;5241.584772111528;3358.781679190845;4052.2777819426774;4073.2260870434525;4073.87778366648;4079.6532294754907;3963.1474103585656;3971.9602803972025;4065.5527891931865;4073.348264277701;4089.3661482470266;4011.879643610682;4053.378648540578;4063.7764922929227;4065.897298889889;4081.2245673322122;3542.71658267338;4003.859864904729;4022.9597704022876;4053.7365071270274;4070.4097905803787;3531.4638132412483;3537.204129070964;3537.345591065661;3551.5383000210068;3564.6473327603917;4326.783660816978;4768.761561921925;4877.439094504107;5032.496750324938;5057.721825299607;4086.427900093989;4603.941093548503;4614.538546145359;4629.675922685414;4643.907121857544;5016.3227964224725;5136.4333878167145;5455.781768729285;5483.561315094783;5521.66869987798;3308.5698859148397;3746.3069332173636;3885.61143885612;3918.431827385013;3923.1761330120707;5128.897422051576;5699.68651724155;5758.568107391962;5784.479396854261;5789.334226563915;3924.941125883114;3936.389859571771;3942.5071866016747;3943.684505239574;3954.339177545438;3278.065751260879;3419.6238413774463;3976.687693061283;3979.46277252573;4003.259397011562;3917.9608203918165;4589.5181005994555;4619.422572178478;4624.815007399667;4641.2109941310055;5475.945240547623;5515;5541.196526503715;5592.328920529562;5597.496225339739;1293.198217105382;1334.6062911441147;1335.8730920562532;1337.3848029906353;1343.7312537492346;668.1748041169152;807.9617691266694;835.5294681360728;839.5802098950525;856.6701819799298;3860.3051450739135;4268.570029040244;4301.41930839339;4333.068390296102;4350.912981740347;4034.8991275218355;4270.871873843789;4302.548232435612;4722.527747225318;4790.939895844751;3748;3890.358090915024;4279.828806847691;4293.5401963332615;4313.856827940598;2362.8582285062953;2370.205980996351;2444.5477586646525;2521.2184222890965;2537.1627362970175;4049.5500222365636;4220.240356735756;4224.281872081753;4741.933065060334;4910.660750689494;3895.8441662333757;4300.7634580097765;4846.825329409243;4873;4880.365552478155;4349.760763158057;4622;4672.649551283667;4877.097736918681;4905.724511627002;4040.1617264144156;4354.934675979926;4585.10590434862;4676.205045142266;4705.55297246761;4308.849190278325;4537.773111344353;4744.5492678195515;4790.137775200497;4805.8077676892535;4222.543222588202;4311.741295522279;4457.8216871324785;4550.544945505423;4810.062037902575;3065.11111777582;3254.0313110568713;3313.304206116646;3431.747412194581;3442.504184466242;4032.7378720384254;4191.6856235781825;4215.59951804578;4654.743989080633;4837.073570489803;4124.411761767162;4221.163793749729;4596.379488769107;4610.377599024089;4615;4172.248995180897;4570.194773064687;4585.931211031904;4590.472095709036;4625.306204069282;4203.55862634419;4860.881997140748;5128.692278463304;5375.973120134531;5469.945300546943;4217.683673724544;4291.527931927423;4704.388429504143;4794.113089078532;5460.778140896889;4435.756033418193;5012.020252000367;5202.26880887537;5430.294061771944;5470.945290547043 20:36:39 INFO - PROCESS | 1667 | |5;yui.html;659.1134923527854;839.1063517354017;840.7183593496178;844.6368061733452;858.8282343531291;14255.144691318515;15073.39706411745;15083.547493575197;15098.169600671987;15139.091654500739;11408.885911140886;13840.446382144703;13843.169409835416;13918;13918.930405347985;718.6370882704227;749.7713197474768;749.947503674743;752.0524139584136;753.1150897695186;851.4210336970857;887.6671248281895;920.6823645842173;921.1893533690343;927.6057675487924;949.9572519236651;963.629002833907;970.4613939263705;971.8104969530945;980.1276863591373;963.9974426596332;965.7295957132014;966.8839739231267;972.2270794718181;973.6202880876464;558.0151033426006;700.9859802803967;719.316649183278;723.9312265334785;726.3172617739308;14522.782158267635;14529.709405811824;14544.854551454457;14563.980521363508;14565.053271537316;13394.526602073764;13809.24049177295;13842.584722458343;13859.861401386059;13944.51194208198;11910.166288359818;11972.58095966637;12070.27578345302;12163.574274900337;12691.238525688395;4473.530279320689;4480.686351955364;4586.701864378805;4653.441587009546;4658.208104622222;76299.6185019077;77714.44571108602;78167.43665126723;78589;79078.60460697717;9018;10861.456927153611;10877.129829613612;10912.836307455394;10999.285046471954;20958.580828383347;20971.951402430124;21014.054367553497;21018.054187561596;21045;7709.151993280735;7774.883376749353;7803.453758236869;7856.764297071097;7965.960170199228;4292.334688123346;4294.119705460374;4359.782010899442;4374.081442896963;4471.61991230749;2848.7293707097792;3110.6578276389578;3417.5386322846593;3425.8629654814026;3430.142464383904;652.7845810882397;657.2934095846983;668.4919461209515;679.2629996453804;689.2039694153261;615.3385111005687;624.2122917224896;639.9008153736179;666.167290886392;677.2178134254845;8710.608022638997;8734.781630459289;8845.911540884508;8850.645974160961;8963.596638151299;629.5907660020931;650.9511786616023;658.716751796732;668.3617145625916;676.1851968378093;4150.91698166035;4172.415861779354;4194.706370554032;4215.641670457984;4377.139715620849;86639.56680216559;87132.56433717918;88114;89486.26164860258;91266;76704;78530;79083.60458197787;79779.60110199412;82376.58811705903;69316.306836932;69584;69904.65047674831;70583.58832823315;70870.64564677143;8156.796080097808;8226.547059435348;8230.588470576327;8269.95865020671;8323.084460709197;39442.802785985885;41928.74213773591;43839;43982.12035759203;44172.779136104116;60247;60610;60865.39134608597;60921.08618370639;61147;11123.944380278046;11181.664550063511;11278.774424511299;11404.315741055563;11823.290602563873;9535.570899309687;11810.114241432098;11882.940585297018;11883.405829708307;12246.510139594317;11381.601643942422;11387.715307117389;11441.199116061796;11689.590864319707;11836.763264734485;11372.886271137182;11481.655550333504;11508.59719909799;11600.593979210687;11850.703732406759;11046.613368532384;11106.61126860556;11135;11185.32888026721;11264.943675281898;9496.715098547054;9654.179394751525;9700.75748106303;9991.25065620067;9998.800023999625;9433.433993960385;9598.856017159887;9685.515724213901;9837.213022958003;9956.800863982824 20:36:39 INFO - PROCESS | 1667 | __end_tp_report 20:36:39 INFO - PROCESS | 1667 | __start_cc_report 20:36:39 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1124 20:36:39 INFO - PROCESS | 1667 | __end_cc_report 20:36:39 INFO - PROCESS | 1667 | __startTimestamp1464579399364__endTimestamp 20:36:39 INFO - PROCESS | 1667 | 20:36:39 INFO - PROCESS | 1667 | ------- Summary: start ------- 20:36:39 INFO - PROCESS | 1667 | Number of tests: 6 20:36:39 INFO - PROCESS | 1667 | 20:36:39 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5759.43 Median:5992.10 stddev:622.67 (10.4%) stddev-sans-first:598.03 20:36:39 INFO - PROCESS | 1667 | Values: 3466.6 3468.1 3481.9 3499.4 3539.2 5599.3 5634.5 5717.8 5754.9 5763.1 4830.4 5666.3 5758.9 5766.5 5780.8 5208.3 5243.2 5268.1 5277.8 5328.4 5280.8 5307.2 5319.9 5323.1 5324.8 5190.7 5204.6 5204.7 5226.5 5312.7 5304.8 5336.9 5342.4 5352.9 5356.6 5122.9 5123.0 5130.3 5227.7 5233.1 5521.8 5522.8 5549.7 5567.1 5600.5 6264.9 6269.2 6283.5 6302.0 6322.7 6116.5 6235.1 6314.6 6319.1 6328.8 5907.2 5911.7 5931.7 5938.2 5941.3 6258.4 6275.0 6285.9 6294.9 6297.6 6046.6 6106.3 6121.1 6129.3 6187.8 6273.2 6289.5 6300.8 6303.4 6306.4 6155.9 6215.7 6230.6 6235.5 6238.4 6143.9 6148.3 6151.0 6213.5 6233.8 4942.3 4971.7 4973.9 4977.9 5008.1 4503.3 4507.5 4513.2 4515.3 4544.5 4958.8 5605.9 5666.3 5694.3 5721.8 6228.6 6260.4 6280.0 6295.7 6309.2 5719.8 5727.4 5745.1 5745.5 5769.8 5305.4 5352.6 5398.1 5419.9 5422.7 6261.9 6306.4 6316.5 6320.8 6340.6 6127.9 6210.3 6314.2 6331.2 6338.1 6225.7 6233.0 6262.2 6342.4 6362.1 6206.9 6237.0 6245.3 6246.9 6249.2 6108.4 6157.7 6306.8 6307.9 6324.8 5997.7 6064.7 6113.8 6132.8 6142.7 6078.7 6131.0 6164.4 6167.4 6169.1 5738.5 5834.2 5883.4 5979.8 5987.3 5947.6 6032.0 6042.6 6064.0 6105.3 5877.3 5992.1 5993.3 6010.5 6016.2 20:36:39 INFO - PROCESS | 1667 | 20:36:39 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:71860.12 Median:35988.27 stddev:160852.37 (447.0%) stddev-sans-first:161214.36 20:36:39 INFO - PROCESS | 1667 | Values: 497.2 527.0 532.9 538.2 538.3 28934.9 42107.9 43119.0 43234.8 45551.8 44859.8 45183.0 45316.5 45321.5 45357.0 8716.2 9483.1 9701.1 9708.0 9716.6 8688.6 11787.5 12140.8 12163.9 12169.0 11927.6 12014.9 12021.3 12025.0 12042.3 8683.8 11615.5 11866.3 11905.0 11992.5 11865.2 11967.3 11970.3 11984.3 11984.3 41259.0 41728.4 41734.0 41791.8 41885.4 3507.4 3521.2 3638.8 3838.8 4247.5 31664.5 32345.4 32365.7 32637.2 33071.8 3257.2 3283.5 3304.0 3319.0 3430.1 501806.5 503863.5 505300.0 505621.0 505762.0 34673.8 61328.7 61783.1 61935.4 62031.1 35487.5 36365.5 36481.3 36516.6 36616.5 12551.4 14954.5 15102.8 15111.8 15138.4 9788.3 10090.7 11651.9 11999.2 12773.5 7522.4 7898.6 7998.8 7998.8 8184.5 3277.5 3319.2 3322.2 3330.7 3347.9 993.6 1027.1 1059.5 1066.1 1145.1 30606.5 30723.1 30969.8 31408.4 31543.8 1089.7 1142.8 1158.3 1179.9 1197.3 15815.1 16088.0 16113.0 16236.4 16326.5 702824.0 709636.0 710911.0 711616.0 713113.4 59813.8 61496.7 61610.8 61644.4 61657.1 1302.5 1345.2 1422.2 1433.7 1442.7 30751.2 31742.4 32064.4 32585.8 32742.0 50899.0 51189.7 51429.0 51446.7 51498.7 563765.3 563983.0 569002.0 572152.0 574829.0 24333.3 26650.5 27159.5 27208.9 27981.9 48518.3 48622.0 49035.0 50128.7 50471.5 39926.2 40259.0 40418.8 40645.8 40861.4 39484.6 40133.2 40336.0 40680.0 41078.0 38276.8 38882.0 39222.0 39290.2 39345.6 33915.8 34570.3 35465.6 35596.5 35611.0 21974.3 24352.9 25333.0 25837.9 26450.0 20:36:39 INFO - PROCESS | 1667 | 20:36:39 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:33606.68 Median:12072.97 stddev:98511.91 (816.0%) stddev-sans-first:98756.62 20:36:39 INFO - PROCESS | 1667 | Values: 355.6 379.1 385.8 401.9 407.1 16216.6 19409.0 20220.0 20675.4 20728.4 20538.6 20761.9 20990.6 21873.3 21915.6 5266.2 5529.9 5632.1 5817.7 5827.4 5751.7 5760.7 5808.7 5823.7 5837.8 5463.9 5678.3 5711.1 5724.5 5733.8 5426.0 5681.5 5710.3 5714.8 5717.9 4964.1 4995.3 5002.0 5011.9 5018.3 24714.6 25444.4 25451.9 25638.9 25675.9 2230.4 2240.9 2266.4 2279.5 2521.3 15401.4 15655.5 15792.3 15802.0 16171.4 1759.0 1848.1 1872.5 1875.2 1955.3 83877.6 108286.0 108552.0 109564.0 109570.4 10422.5 10489.4 10874.5 10902.4 11032.3 13647.7 13710.0 13734.2 13743.9 13812.0 8375.4 8585.4 8589.5 8697.1 8738.0 8628.0 8663.0 8672.5 8768.6 8792.7 5628.7 6459.8 6470.8 6581.7 6711.9 1658.2 1780.4 1823.2 1827.7 1831.7 821.7 952.5 982.2 1063.9 1072.2 20423.9 21035.5 21118.4 21301.9 21307.7 966.0 1003.8 1097.5 1100.6 1154.6 11333.8 11616.5 11660.2 11679.4 11680.0 582950.0 590116.0 592761.0 604958.0 606557.0 94011.5 100555.5 116359.0 116919.0 118131.0 942.6 981.3 998.2 1019.8 1020.3 15787.0 17486.3 19277.0 19277.3 19603.6 46840.5 47944.8 48043.5 48196.0 48396.5 63688.7 63836.7 64534.4 64903.0 65064.0 6880.6 7710.8 7836.4 7870.5 7903.1 12215.9 12410.3 12472.4 12633.0 12652.4 10893.6 10970.8 11022.7 11063.7 11106.8 10638.5 10679.8 10698.5 10757.9 10922.5 10805.0 10998.5 11096.0 11221.7 11285.3 6751.9 7199.2 7297.8 7385.7 7446.6 10213.1 10428.9 10916.6 11115.5 11199.5 20:36:39 INFO - PROCESS | 1667 | 20:36:39 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5602.19 Median:5033.88 stddev:3126.51 (62.1%) stddev-sans-first:3119.29 20:36:39 INFO - PROCESS | 1667 | Values: 1395.4 1536.5 1580.7 1809.4 1958.8 7753.8 8589.7 8756.1 8786.6 8917.4 8561.5 8768.2 8772.2 8774.5 8786.9 2331.3 2364.2 2365.5 2365.9 2367.1 2336.8 2371.9 2376.0 2376.3 2384.9 2329.9 2350.3 2361.7 2365.6 2375.0 2314.5 2341.1 2341.6 2349.6 2354.6 2308.6 2336.1 2343.4 2343.6 2345.6 8489.5 8649.3 8716.1 8741.3 8742.8 5197.4 5399.5 5405.5 5413.3 5414.1 3523.5 3569.7 3642.3 3643.1 3647.9 1066.6 1077.4 1083.3 1086.2 1101.8 11524.0 11631.4 11636.9 11643.4 11647.2 4449.4 4465.8 4571.5 4606.1 4609.7 9090.5 9091.6 9092.0 9093.8 9099.0 5193.0 5195.5 5196.3 5201.5 5202.7 4001.8 4412.2 4563.4 4785.5 4862.4 3738.4 3739.3 3807.7 3839.5 3865.6 1009.9 1163.7 1172.7 1178.6 1179.0 646.3 774.8 848.0 936.8 968.5 5601.5 5833.6 5867.4 5884.7 5891.2 921.6 926.0 930.6 945.9 981.7 3795.0 3837.8 3920.2 3927.6 3954.8 10306.4 10511.0 10690.8 10718.9 10721.6 10482.2 10626.0 10642.6 10658.8 10682.7 7898.0 8011.1 8090.2 8184.6 8204.4 5636.9 5678.1 5694.9 5707.6 5825.9 9484.7 9636.6 9947.7 9972.6 9984.9 8397.9 9925.6 10078.4 10229.1 10354.3 6628.8 6856.8 6926.4 6986.9 6994.0 7363.6 7961.6 8004.5 8021.8 8100.0 7144.5 7303.5 7367.1 7395.7 7414.9 7116.7 7223.5 7224.1 7341.9 7371.3 6893.1 6938.3 6975.2 6977.1 6994.6 4740.0 4907.4 4963.8 4974.2 5011.3 4940.0 5006.1 5029.2 5038.6 5159.9 20:36:39 INFO - PROCESS | 1667 | 20:36:39 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:4103.30 Median:4320.32 stddev:1199.67 (27.8%) stddev-sans-first:1168.64 20:36:39 INFO - PROCESS | 1667 | Values: 303.2 310.8 319.3 321.5 323.5 4595.7 5100.8 5262.5 5324.5 5342.7 4615.4 5005.4 5108.5 5121.7 5241.6 3358.8 4052.3 4073.2 4073.9 4079.7 3963.1 3972.0 4065.6 4073.3 4089.4 4011.9 4053.4 4063.8 4065.9 4081.2 3542.7 4003.9 4023.0 4053.7 4070.4 3531.5 3537.2 3537.3 3551.5 3564.6 4326.8 4768.8 4877.4 5032.5 5057.7 4086.4 4603.9 4614.5 4629.7 4643.9 5016.3 5136.4 5455.8 5483.6 5521.7 3308.6 3746.3 3885.6 3918.4 3923.2 5128.9 5699.7 5758.6 5784.5 5789.3 3924.9 3936.4 3942.5 3943.7 3954.3 3278.1 3419.6 3976.7 3979.5 4003.3 3918.0 4589.5 4619.4 4624.8 4641.2 5475.9 5515.0 5541.2 5592.3 5597.5 1293.2 1334.6 1335.9 1337.4 1343.7 668.2 808.0 835.5 839.6 856.7 3860.3 4268.6 4301.4 4333.1 4350.9 4034.9 4270.9 4302.5 4722.5 4790.9 3748.0 3890.4 4279.8 4293.5 4313.9 2362.9 2370.2 2444.5 2521.2 2537.2 4049.6 4220.2 4224.3 4741.9 4910.7 3895.8 4300.8 4846.8 4873.0 4880.4 4349.8 4622.0 4672.6 4877.1 4905.7 4040.2 4354.9 4585.1 4676.2 4705.6 4308.8 4537.8 4744.5 4790.1 4805.8 4222.5 4311.7 4457.8 4550.5 4810.1 3065.1 3254.0 3313.3 3431.7 3442.5 4032.7 4191.7 4215.6 4654.7 4837.1 4124.4 4221.2 4596.4 4610.4 4615.0 4172.2 4570.2 4585.9 4590.5 4625.3 4203.6 4860.9 5128.7 5376.0 5469.9 4217.7 4291.5 4704.4 4794.1 5460.8 4435.8 5012.0 5202.3 5430.3 5470.9 20:36:39 INFO - PROCESS | 1667 | 20:36:39 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:17902.65 Median:626.90 stddev:24634.31 (3929.5%) stddev-sans-first:24669.39 20:36:39 INFO - PROCESS | 1667 | Values: 659.1 839.1 840.7 844.6 858.8 14255.1 15073.4 15083.5 15098.2 15139.1 11408.9 13840.4 13843.2 13918.0 13918.9 718.6 749.8 749.9 752.1 753.1 851.4 887.7 920.7 921.2 927.6 950.0 963.6 970.5 971.8 980.1 964.0 965.7 966.9 972.2 973.6 558.0 701.0 719.3 723.9 726.3 14522.8 14529.7 14544.9 14564.0 14565.1 13394.5 13809.2 13842.6 13859.9 13944.5 11910.2 11972.6 12070.3 12163.6 12691.2 4473.5 4480.7 4586.7 4653.4 4658.2 76299.6 77714.4 78167.4 78589.0 79078.6 9018.0 10861.5 10877.1 10912.8 10999.3 20958.6 20972.0 21014.1 21018.1 21045.0 7709.2 7774.9 7803.5 7856.8 7966.0 4292.3 4294.1 4359.8 4374.1 4471.6 2848.7 3110.7 3417.5 3425.9 3430.1 652.8 657.3 668.5 679.3 689.2 615.3 624.2 639.9 666.2 677.2 8710.6 8734.8 8845.9 8850.6 8963.6 629.6 651.0 658.7 668.4 676.2 4150.9 4172.4 4194.7 4215.6 4377.1 86639.6 87132.6 88114.0 89486.3 91266.0 76704.0 78530.0 79083.6 79779.6 82376.6 69316.3 69584.0 69904.7 70583.6 70870.6 8156.8 8226.5 8230.6 8270.0 8323.1 39442.8 41928.7 43839.0 43982.1 44172.8 60247.0 60610.0 60865.4 60921.1 61147.0 11123.9 11181.7 11278.8 11404.3 11823.3 9535.6 11810.1 11882.9 11883.4 12246.5 11381.6 11387.7 11441.2 11689.6 11836.8 11372.9 11481.7 11508.6 11600.6 11850.7 11046.6 11106.6 11135.0 11185.3 11264.9 9496.7 9654.2 9700.8 9991.3 9998.8 9433.4 9598.9 9685.5 9837.2 9956.8 20:36:39 INFO - PROCESS | 1667 | -------- Summary: end -------- 20:36:39 INFO - PROCESS | 1667 | 20:36:39 INFO - TEST-INFO | 1667: exit 0 20:36:39 INFO - TEST-OK | dromaeo_css | took 1115655ms 20:36:39 INFO - TEST-START | kraken 20:36:39 INFO - operating with platform_type : mac_ 20:36:39 INFO - Initialising browser for kraken test... 20:36:39 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmpG1wA8e/profile http://localhost:49275/getInfo.html) 20:36:42 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 20:36:42 INFO - PROCESS | 1674 | colorDepth:24 20:36:42 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 20:36:42 INFO - PROCESS | 1674 | __metrics 20:36:42 INFO - PROCESS | 1674 | 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" 20:36:42 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:36:42 INFO - TEST-INFO | 1674: exit 0 20:36:42 INFO - Browser initialized. 20:36:42 INFO - Running cycle 1/1 for kraken test... 20:36:42 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmpG1wA8e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:36:54 INFO - PROCESS | 1675 | RSS: Main: 241524736 20:36:54 INFO - PROCESS | 1675 | 20:36:57 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 20:36:58 INFO - PROCESS | 1675 | RSS: Main: 291811328 20:36:58 INFO - PROCESS | 1675 | 20:37:01 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 20:37:01 INFO - PROCESS | 1675 | RSS: Main: 303140864 20:37:01 INFO - PROCESS | 1675 | 20:37:05 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 20:37:05 INFO - PROCESS | 1675 | RSS: Main: 389668864 20:37:05 INFO - PROCESS | 1675 | 20:37:08 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 20:37:08 INFO - PROCESS | 1675 | RSS: Main: 288980992 20:37:08 INFO - PROCESS | 1675 | 20:37:10 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:37:11 INFO - PROCESS | 1675 | RSS: Main: 292671488 20:37:11 INFO - PROCESS | 1675 | 20:37:14 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 20:37:14 INFO - PROCESS | 1675 | RSS: Main: 330342400 20:37:14 INFO - PROCESS | 1675 | 20:37:17 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 20:37:18 INFO - PROCESS | 1675 | RSS: Main: 332165120 20:37:18 INFO - PROCESS | 1675 | 20:37:21 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 20:37:21 INFO - PROCESS | 1675 | RSS: Main: 329011200 20:37:21 INFO - PROCESS | 1675 | 20:37:23 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:37:24 INFO - PROCESS | 1675 | RSS: Main: 311365632 20:37:24 INFO - PROCESS | 1675 | 20:37:25 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:37:26 INFO - PROCESS | 1675 | RSS: Main: 318566400 20:37:26 INFO - PROCESS | 1675 | 20:37:28 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:37:29 INFO - PROCESS | 1675 | RSS: Main: 322969600 20:37:29 INFO - PROCESS | 1675 | 20:37:32 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:37:32 INFO - PROCESS | 1675 | RSS: Main: 325332992 20:37:32 INFO - PROCESS | 1675 | 20:37:35 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:37:36 INFO - PROCESS | 1675 | RSS: Main: 324972544 20:37:36 INFO - PROCESS | 1675 | 20:37:38 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 20:37:38 INFO - PROCESS | 1675 | RSS: Main: 368799744 20:37:38 INFO - PROCESS | 1675 | 20:37:38 INFO - PROCESS | 1675 | __start_tp_report 20:37:38 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 20:37:38 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 20:37:38 INFO - PROCESS | 1675 | |i|pagename|runs| 20:37:38 INFO - PROCESS | 1675 | |0;ai-astar;113;109;111;112;112;112;112;111;112;113 20:37:38 INFO - PROCESS | 1675 | |1;audio-beat-detection;154;120;122;196;106;130;166;139;157;122 20:37:38 INFO - PROCESS | 1675 | |2;audio-dft;182;182;181;182;182;233;229;181;182;182 20:37:38 INFO - PROCESS | 1675 | |3;audio-fft;78;73;59;115;59;83;85;82;112;55 20:37:38 INFO - PROCESS | 1675 | |4;audio-oscillator;87;116;126;56;83;98;127;100;67;100 20:37:38 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;87;83;87;84;87;87;134;84;87;85 20:37:38 INFO - PROCESS | 1675 | |6;imaging-darkroom;93;92;93;97;93;93;93;93;93;92 20:37:38 INFO - PROCESS | 1675 | |7;imaging-desaturate;126;133;85;84;82;84;87;84;86;87 20:37:38 INFO - PROCESS | 1675 | |8;json-parse-financial;57;57;55;55;55;55;56;54;55;55 20:37:38 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;64;70;82;75;71;71;69;70;69;69 20:37:38 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;87;85;87;85;80;80;84;86;141;86 20:37:38 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;137;140;139;135;138;140;140;142;164;139 20:37:38 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;164;164;166;166;179;216;221;166;161;162 20:37:38 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;66;65;66;65;66;66;67;66;66;63 20:37:38 INFO - PROCESS | 1675 | __end_tp_report 20:37:38 INFO - PROCESS | 1675 | __start_cc_report 20:37:38 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1893 20:37:38 INFO - PROCESS | 1675 | __end_cc_report 20:37:38 INFO - PROCESS | 1675 | __startTimestamp1464579458316__endTimestamp 20:37:38 INFO - PROCESS | 1675 | 20:37:38 INFO - PROCESS | 1675 | ------- Summary: start ------- 20:37:38 INFO - PROCESS | 1675 | Number of tests: 14 20:37:38 INFO - PROCESS | 1675 | 20:37:38 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:1.16 (1.0%) stddev-sans-first:1.13 20:37:38 INFO - PROCESS | 1675 | Values: 113.0 109.0 111.0 112.0 112.0 112.0 112.0 111.0 112.0 113.0 20:37:38 INFO - PROCESS | 1675 | 20:37:38 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:141.20 Median:146.50 stddev:27.05 (18.5%) stddev-sans-first:28.30 20:37:38 INFO - PROCESS | 1675 | Values: 154.0 120.0 122.0 196.0 106.0 130.0 166.0 139.0 157.0 122.0 20:37:38 INFO - PROCESS | 1675 | 20:37:38 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:191.60 Median:182.00 stddev:20.79 (11.4%) stddev-sans-first:21.76 20:37:38 INFO - PROCESS | 1675 | Values: 182.0 182.0 181.0 182.0 182.0 233.0 229.0 181.0 182.0 182.0 20:37:38 INFO - PROCESS | 1675 | 20:37:38 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:80.10 Median:75.50 stddev:20.67 (27.4%) stddev-sans-first:21.91 20:37:38 INFO - PROCESS | 1675 | Values: 78.0 73.0 59.0 115.0 59.0 83.0 85.0 82.0 112.0 55.0 20:37:38 INFO - PROCESS | 1675 | 20:37:38 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:61.50 stddev:23.54 (38.3%) stddev-sans-first:24.74 20:37:38 INFO - PROCESS | 1675 | Values: 87.0 116.0 126.0 56.0 83.0 98.0 127.0 100.0 67.0 100.0 20:37:38 INFO - PROCESS | 1675 | 20:37:38 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:90.50 Median:87.00 stddev:15.36 (17.7%) stddev-sans-first:16.24 20:37:38 INFO - PROCESS | 1675 | Values: 87.0 83.0 87.0 84.0 87.0 87.0 134.0 84.0 87.0 85.0 20:37:38 INFO - PROCESS | 1675 | 20:37:38 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:93.20 Median:93.00 stddev:1.40 (1.5%) stddev-sans-first:1.48 20:37:38 INFO - PROCESS | 1675 | Values: 93.0 92.0 93.0 97.0 93.0 93.0 93.0 93.0 93.0 92.0 20:37:38 INFO - PROCESS | 1675 | 20:37:38 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:84.50 stddev:18.95 (22.4%) stddev-sans-first:16.12 20:37:38 INFO - PROCESS | 1675 | Values: 126.0 133.0 85.0 84.0 82.0 84.0 87.0 84.0 86.0 87.0 20:37:38 INFO - PROCESS | 1675 | 20:37:38 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:55.00 stddev:0.97 (1.8%) stddev-sans-first:0.83 20:37:38 INFO - PROCESS | 1675 | Values: 57.0 57.0 55.0 55.0 55.0 55.0 56.0 54.0 55.0 55.0 20:37:38 INFO - PROCESS | 1675 | 20:37:38 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:70.50 stddev:4.71 (6.7%) stddev-sans-first:4.27 20:37:38 INFO - PROCESS | 1675 | Values: 64.0 70.0 82.0 75.0 71.0 71.0 69.0 70.0 69.0 69.0 20:37:38 INFO - PROCESS | 1675 | 20:37:38 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:85.50 stddev:18.06 (21.1%) stddev-sans-first:19.13 20:37:38 INFO - PROCESS | 1675 | Values: 87.0 85.0 87.0 85.0 80.0 80.0 84.0 86.0 141.0 86.0 20:37:38 INFO - PROCESS | 1675 | 20:37:38 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:141.40 Median:140.00 stddev:8.17 (5.8%) stddev-sans-first:8.51 20:37:38 INFO - PROCESS | 1675 | Values: 137.0 140.0 139.0 135.0 138.0 140.0 140.0 142.0 164.0 139.0 20:37:38 INFO - PROCESS | 1675 | 20:37:38 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.50 Median:166.00 stddev:22.71 (13.7%) stddev-sans-first:23.63 20:37:38 INFO - PROCESS | 1675 | Values: 164.0 164.0 166.0 166.0 179.0 216.0 221.0 166.0 161.0 162.0 20:37:38 INFO - PROCESS | 1675 | 20:37:38 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:1.07 (1.6%) stddev-sans-first:1.13 20:37:38 INFO - PROCESS | 1675 | Values: 66.0 65.0 66.0 65.0 66.0 66.0 67.0 66.0 66.0 63.0 20:37:38 INFO - PROCESS | 1675 | -------- Summary: end -------- 20:37:38 INFO - PROCESS | 1675 | 20:37:39 INFO - TEST-INFO | 1675: exit 0 20:37:39 INFO - TEST-OK | kraken | took 59253ms 20:37:39 INFO - SUITE-END | took 1174s 20:37:39 INFO - Completed test suite (00:19:35) 20:37:39 INFO - javascript benchmark 20:37:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3466.6013408458034, 3468.1156305142185, 3481.8781342653006, 3499.440089585667, 3539.1682954505704, 5599.300087489064, 5634.549236061115, 5717.828465146053, 5754.856128596787, 5763.0779075347955, 4830.396200474941, 5666.291713535808, 5758.942410575893, 5766.481016708495, 5780.797672081478, 5208.270842082112, 5243.239730239113, 5268.130758424855, 5277.7888884444765, 5328.413874473824, 5280.841574752763, 5307.203919412081, 5319.946800531984, 5323.068463018975, 5324.840254792362, 5190.71451070191, 5204.635675502716, 5204.661696989683, 5226.503482169188, 5312.654677445954, 5304.840854774363, 5336.946630533684, 5342.439043900412, 5352.866178345534, 5356.558312088955, 5122.948770512322, 5122.974385128088, 5130.333056702642, 5227.738613069331, 5233.058049551079, 5521.779128834842, 5522.779088836442, 5549.667019978814, 5567.137093750434, 5600.4959553640365, 6264.906026409608, 6269.153664255313, 6283.5287353448675, 6301.968490157566, 6322.715477803464, 6116.482126224659, 6235.127082208494, 6314.62112273265, 6319.052142178664, 6328.841778955517, 5907.202527658797, 5911.733971971272, 5931.73307201177, 5938.168656388109, 5941.287045554474, 6258.374162583706, 6274.964630835952, 6285.9057114143325, 6294.905576416358, 6297.622142671455, 6046.606970546901, 6106.297775755756, 6121.056693971993, 6129.297663865929, 6187.81436556904, 6273.153124328157, 6289.496840252768, 6300.779472718433, 6303.36966303366, 6306.369363063749, 6155.907661385083, 6215.720292586844, 6230.56386052981, 6235.469985051231, 6238.407351301619, 6143.938560614337, 6148.3399482139575, 6151.046587778901, 6213.471854892295, 6233.812985610348, 4942.332785073969, 4971.726555039508, 4973.90052198954, 4977.925331120036, 5008.098542262355, 4503.256962601135, 4507.5267096955, 4513.232750432434, 4515.277555591156, 4544.477385100756, 4958.752062396866, 5605.915911261334, 5666.34836993751, 5694.345150307768, 5721.828345149652, 6228.552415289876, 6260.405261500241, 6280.026595877646, 6295.685215739195, 6309.179806625201, 5719.799807006818, 5727.398623144593, 5745.1382292656435, 5745.482906538432, 5769.798057067983, 5305.442928492453, 5352.6253162279045, 5398.082326004589, 5419.945800542023, 5422.728863556807, 6261.906071409024, 6306.400891915261, 6316.494680425647, 6320.810375688737, 6340.556161068773, 6127.922628246602, 6210.285817131088, 6314.210723659543, 6331.176946996861, 6338.080978258219, 6225.719842607003, 6232.968835155795, 6262.15460912771, 6342.39747224013, 6362.109304697438, 6206.937930620636, 6237.033259844731, 6245.28179259391, 6246.937530624818, 6249.156363890998, 6108.433136212153, 6157.692115394123, 6306.810795676137, 6307.873842523216, 6324.778632747832, 5997.670128142994, 6064.696765161812, 6113.816585502442, 6132.753252973555, 6142.744345496893, 6078.7264573093435, 6131.0, 6164.3835616438, 6167.444929956326, 6169.074638804215, 5738.48353648157, 5834.212381328636, 5883.411658834082, 5979.760809567569, 5987.251593550806, 5947.6431414115295, 6031.969840150771, 6042.637441753333, 6063.969680151571, 6105.297890742621, 5877.324107727666, 5992.101184822312, 5993.31076926159, 6010.459058684739, 6016.157737916607], "value": 5721.148028013576, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.2119191082134, 526.9657472264305, 532.9360476742792, 538.1550964984975, 538.2518299563608, 28934.85532572337, 42107.94730131744, 43119.0, 43234.78382608091, 45551.77224113883, 44859.77570112153, 45183.0, 45316.54683453165, 45321.54678453215, 45357.0, 8716.215540601344, 9483.146516813467, 9701.126898579127, 9708.0, 9716.562754676057, 8688.56557172142, 11787.528498860036, 12140.757184856298, 12163.878361216362, 12169.0, 11927.642170734849, 12014.879851201507, 12021.278723276631, 12025.0, 12042.337671428068, 8683.826323473559, 11615.477303521362, 11866.347350895698, 11905.047596192284, 11992.460339284753, 11865.169438139334, 11967.281963082243, 11970.34163121028, 11984.28094314344, 11984.28094314344, 41259.0, 41728.37407438891, 41734.0, 41791.791041044904, 41885.371719424234, 3507.386207413718, 3521.154922818505, 3638.7634803737674, 3838.827252773604, 4247.511536173349, 31664.525032124307, 32345.353092938243, 32365.6763432365, 32637.184070398194, 33071.834640826644, 3257.1857035741064, 3283.4910588858766, 3303.966960330414, 3318.983405082983, 3430.125318043883, 501806.4909675465, 503863.4806825979, 505300.0, 505621.0, 505762.0, 34673.826630866686, 61328.69335653293, 61783.073253901224, 61935.380646193866, 62031.06953395703, 35487.467687984186, 36365.5453781851, 36481.27037459236, 36516.634833651326, 36616.45075323872, 12551.372431378395, 14954.476593319181, 15102.848971510364, 15111.773323400157, 15138.394464221528, 9788.314817962817, 10090.747731306776, 11651.883481165249, 11999.1600587958, 12773.489060437665, 7522.435817313723, 7898.565578893216, 7998.760037198894, 7998.800029999296, 8184.549849758201, 3277.5083737439577, 3319.170207448138, 3322.235885746292, 3330.700236978635, 3347.8660853565584, 993.5529011944653, 1027.0551092994315, 1059.4543809937888, 1066.1097983183972, 1145.061049939042, 30606.54090188649, 30723.078307650805, 30969.845150774552, 31408.37183256322, 31543.84228078845, 1089.7112265249716, 1142.7600203957263, 1158.3281696615943, 1179.9115066369884, 1197.329495482515, 15815.130167840649, 16087.95428297157, 16113.0, 16236.420162783508, 16326.489804692048, 702824.0, 709636.0, 710911.0, 711616.0, 713113.4344328245, 59813.80372392528, 61496.69251653713, 61610.76778464495, 61644.38355616386, 61657.07514387288, 1302.4920281090383, 1345.239939434236, 1422.217780220895, 1433.7132573485344, 1442.7114577084415, 30751.2312192197, 31742.36515269728, 32064.35871282608, 32585.837070815443, 32742.0, 50899.0, 51189.74405127951, 51428.97142057063, 51446.742766285926, 51498.74250628723, 563765.2682156105, 563983.0, 569002.0, 572152.0, 574829.0, 24333.27000189997, 26650.46699065969, 27159.456810864067, 27208.86395568089, 27981.86009070023, 48518.272225915935, 48622.0, 49035.0, 50128.749356252985, 50471.49528504668, 39926.201475969734, 40258.99352516211, 40418.79790601146, 40645.79677101596, 40861.38707919324, 39484.60515394809, 40133.1973360537, 40336.0, 40680.0, 41077.97305067278, 38276.80861595674, 38882.0, 39222.0, 39290.2141957165, 39345.60654393419, 33915.830420847735, 34570.308593828486, 35465.64534354623, 35596.466053009746, 35611.0, 21974.340769776933, 24352.878235608707, 25333.0, 25837.870810645825, 26450.0], "value": 19572.106085029947, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.64435564435564, 379.06749396484645, 385.7510712545857, 401.92162528306994, 407.090153506912, 16216.594585135363, 19409.0, 20220.0, 20675.37973860783, 20728.378148655545, 20538.589228215464, 20761.896190519063, 20990.580188396223, 21873.343799686034, 21915.561688766215, 5266.236395722618, 5529.949309631157, 5632.098864181721, 5817.7382017809305, 5827.358990511061, 5751.712414379285, 5760.683162426065, 5808.651480911138, 5823.708814559276, 5837.766489340422, 5463.945360546403, 5678.346990096151, 5711.143328500717, 5724.513416359594, 5733.799317023905, 5426.0, 5681.460261275193, 5710.314762228559, 5714.828555143353, 5717.914231286534, 4964.131277026542, 4995.275685025687, 5002.0, 5011.924821127686, 5018.347614810052, 24714.629280560806, 25444.363890902692, 25451.872740636365, 25638.87180564104, 25675.871620641963, 2230.3754948614414, 2240.8767517786514, 2266.3767463947547, 2279.4757205842748, 2521.344450442898, 15401.3839446422, 15655.530334089995, 15792.289346979298, 15802.0, 16171.35314587415, 1758.9912050439793, 1848.1313782522327, 1872.4757068020976, 1875.1936667233085, 1955.2667749593902, 83877.58061209654, 108286.0, 108552.0, 109564.0, 109570.35644465419, 10422.478876056166, 10489.370637761605, 10874.510647020903, 10902.400367979837, 11032.28290161137, 13647.727045459034, 13710.0, 13734.244616545986, 13743.931280343535, 13811.964102692138, 8375.371847111492, 8585.398852958942, 8589.52757598338, 8697.08680588529, 8737.956310218407, 8627.956860215785, 8663.047064822864, 8672.523011234443, 8768.649254029768, 8792.736217913473, 5628.690422026746, 6459.773907913294, 6470.773522926675, 6581.670916454158, 6711.865762684719, 1658.233980810433, 1780.3946658136038, 1823.1613457809292, 1827.7167039108958, 1831.6519861226325, 821.7247222180637, 952.4713783850029, 982.1995074014656, 1063.8776540697763, 1072.2172813845937, 20423.897880510503, 21035.47411314699, 21118.366449006553, 21301.934903254776, 21307.68038479424, 965.9521813754744, 1003.8494225866178, 1097.4567589043475, 1100.6367898593444, 1154.6362895687832, 11333.829992550118, 11616.477258523386, 11660.242084264666, 11679.357635329969, 11680.007199388147, 582950.0, 590116.0, 592761.0, 604958.0, 606557.0, 94011.52994234985, 100555.49722251343, 116359.0, 116919.0, 118131.0, 942.5993952569993, 981.2787601113176, 998.2114129837347, 1019.8113349030453, 1020.3418794877263, 15787.0, 17486.300547978448, 19277.036148192255, 19277.325293614656, 19603.607927841647, 46840.53159468498, 47944.76027619979, 48043.51956480391, 48196.0, 48396.5160348392, 63688.68155659192, 63836.68081659748, 64534.38664033249, 64903.02645460227, 65064.0, 6880.58716477013, 7710.845783084419, 7836.373090152888, 7870.527768333918, 7903.091144518224, 12215.877841221474, 12410.317432541297, 12472.376381181086, 12633.0, 12652.367381630698, 10893.618723344645, 10970.835437468284, 11022.669319920415, 11063.668089957315, 11106.833397498882, 10638.468076595984, 10679.786404271716, 10698.465076746288, 10757.9462102689, 10922.453877306261, 10805.0, 10998.450077496253, 11096.0, 11221.66335009951, 11285.322880627189, 6751.93248067513, 7199.208087110518, 7297.817554561178, 7385.667644955883, 7446.590437525988, 10213.08082272599, 10428.947855260674, 10916.563337466412, 11115.499802508744, 11199.496022679163], "value": 9516.505399378186, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1395.3581352577814, 1536.4545586316858, 1580.7470804671252, 1809.3938530592252, 1958.8041195880414, 7753.844923101535, 8589.699360522383, 8756.12438756124, 8786.560671966392, 8917.420367676097, 8561.48631082134, 8768.167024132714, 8772.210501054904, 8774.47353158811, 8786.86819697705, 2331.300609817052, 2364.18435639704, 2365.4914193448403, 2365.9053637854463, 2367.088670861722, 2336.754640762721, 2371.905123795055, 2376.0, 2376.251480783548, 2384.856908585482, 2329.9417514562188, 2350.3419042667997, 2361.728401233854, 2365.6042934668544, 2374.988125059372, 2314.5370925814736, 2341.0518739910362, 2341.5785158671433, 2349.5535848188874, 2354.587947109266, 2308.607536718762, 2336.1356298169617, 2343.437574981992, 2343.5781559319316, 2345.566070277004, 8489.533075680834, 8649.264812490946, 8716.084811094806, 8741.256993155526, 8742.781430464289, 5197.402298735656, 5399.514043736043, 5405.5135037846785, 5413.2692086568595, 5414.133738601805, 3523.5067090607336, 3569.714422846192, 3642.25333806569, 3643.0892276930767, 3647.854085836563, 1066.6213494209599, 1077.4128100185417, 1083.3066837224183, 1086.1582273737838, 1101.8126918423886, 11523.962843344056, 11631.360275184777, 11636.883631163664, 11643.417829108595, 11647.184697071209, 4449.443819522559, 4465.754383508938, 4571.519990401027, 4606.055758569485, 4609.654275929319, 9090.500022498825, 9091.590878410487, 9092.0, 9093.772655683528, 9098.999110097884, 5192.9614077184715, 5195.48045195485, 5196.324477817935, 5201.479852014844, 5202.661826981234, 4001.8399264029695, 4412.227860124465, 4563.361129441881, 4785.545373189541, 4862.41651001882, 3738.3831667775057, 3739.2895349883433, 3807.676347510437, 3839.500864887603, 3865.594112618191, 1009.9244304815348, 1163.6567212672282, 1172.6833754886134, 1178.6051672689746, 1178.9764204715857, 646.3149061994301, 774.7797219379387, 847.9915200847912, 936.8126374725082, 968.5157421289356, 5601.5238704710555, 5833.620814647034, 5867.413258674099, 5884.735186916599, 5891.23413956183, 921.635953798246, 926.0461724423855, 930.6417029443713, 945.9027528067546, 981.6662334806057, 3795.0322667719556, 3837.76973381598, 3920.2355540669864, 3927.6465118139504, 3954.7824869631872, 10306.381617102998, 10511.0, 10690.78618427627, 10718.892811071946, 10721.624743133953, 10482.213833962483, 10626.043656070991, 10642.627508037182, 10658.786824263627, 10682.679519614425, 7897.960510197412, 8011.078725946591, 8090.231428014329, 8184.590770461572, 8204.384671149806, 5636.915446268227, 5678.119891416837, 5694.857628559153, 5707.6290041147195, 5825.94174058271, 9484.715458535979, 9636.614535418505, 9947.701568952654, 9972.551235194578, 9984.900150998688, 8397.916020839713, 9925.553350099119, 10078.445685487368, 10229.130523905254, 10354.326968747007, 6628.83427914306, 6856.794296171123, 6926.445884329342, 6986.930130698831, 6993.965030174817, 7363.595002274712, 7961.562114083781, 8004.479708818908, 8021.799455013671, 8099.959500202462, 7144.4641651877355, 7303.488755787044, 7367.115946086289, 7395.704171833282, 7414.888776668462, 7116.679749411185, 7223.494355395072, 7224.13310402755, 7341.889871651822, 7371.336579707852, 6893.103896493624, 6938.306169383223, 6975.162980442176, 6977.092977912839, 6994.5803251805055, 4739.952600473951, 4907.3620429343955, 4963.801447942186, 4974.154393753143, 5011.32347133147, 4939.975300123477, 5006.147725341647, 5029.1953287473825, 5038.571721403721, 5159.948400515947], "value": 4507.770628156097, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.1708277860051, 310.83215063865515, 319.28639490738203, 321.53056537455313, 323.4678953122114, 4595.7242565446095, 5100.846974590759, 5262.526372626463, 5324.467553244678, 5342.65272757271, 4615.353850460938, 5005.399352077747, 5108.463611320804, 5121.692698438096, 5241.584772111528, 3358.781679190845, 4052.2777819426774, 4073.2260870434525, 4073.87778366648, 4079.6532294754907, 3963.1474103585656, 3971.9602803972025, 4065.5527891931865, 4073.348264277701, 4089.3661482470266, 4011.879643610682, 4053.378648540578, 4063.7764922929227, 4065.897298889889, 4081.2245673322122, 3542.71658267338, 4003.859864904729, 4022.9597704022876, 4053.7365071270274, 4070.4097905803787, 3531.4638132412483, 3537.204129070964, 3537.345591065661, 3551.5383000210068, 3564.6473327603917, 4326.783660816978, 4768.761561921925, 4877.439094504107, 5032.496750324938, 5057.721825299607, 4086.427900093989, 4603.941093548503, 4614.538546145359, 4629.675922685414, 4643.907121857544, 5016.3227964224725, 5136.4333878167145, 5455.781768729285, 5483.561315094783, 5521.66869987798, 3308.5698859148397, 3746.3069332173636, 3885.61143885612, 3918.431827385013, 3923.1761330120707, 5128.897422051576, 5699.68651724155, 5758.568107391962, 5784.479396854261, 5789.334226563915, 3924.941125883114, 3936.389859571771, 3942.5071866016747, 3943.684505239574, 3954.339177545438, 3278.065751260879, 3419.6238413774463, 3976.687693061283, 3979.46277252573, 4003.259397011562, 3917.9608203918165, 4589.5181005994555, 4619.422572178478, 4624.815007399667, 4641.2109941310055, 5475.945240547623, 5515.0, 5541.196526503715, 5592.328920529562, 5597.496225339739, 1293.198217105382, 1334.6062911441147, 1335.8730920562532, 1337.3848029906353, 1343.7312537492346, 668.1748041169152, 807.9617691266694, 835.5294681360728, 839.5802098950525, 856.6701819799298, 3860.3051450739135, 4268.570029040244, 4301.41930839339, 4333.068390296102, 4350.912981740347, 4034.8991275218355, 4270.871873843789, 4302.548232435612, 4722.527747225318, 4790.939895844751, 3748.0, 3890.358090915024, 4279.828806847691, 4293.5401963332615, 4313.856827940598, 2362.8582285062953, 2370.205980996351, 2444.5477586646525, 2521.2184222890965, 2537.1627362970175, 4049.5500222365636, 4220.240356735756, 4224.281872081753, 4741.933065060334, 4910.660750689494, 3895.8441662333757, 4300.7634580097765, 4846.825329409243, 4873.0, 4880.365552478155, 4349.760763158057, 4622.0, 4672.649551283667, 4877.097736918681, 4905.724511627002, 4040.1617264144156, 4354.934675979926, 4585.10590434862, 4676.205045142266, 4705.55297246761, 4308.849190278325, 4537.773111344353, 4744.5492678195515, 4790.137775200497, 4805.8077676892535, 4222.543222588202, 4311.741295522279, 4457.8216871324785, 4550.544945505423, 4810.062037902575, 3065.11111777582, 3254.0313110568713, 3313.304206116646, 3431.747412194581, 3442.504184466242, 4032.7378720384254, 4191.6856235781825, 4215.59951804578, 4654.743989080633, 4837.073570489803, 4124.411761767162, 4221.163793749729, 4596.379488769107, 4610.377599024089, 4615.0, 4172.248995180897, 4570.194773064687, 4585.931211031904, 4590.472095709036, 4625.306204069282, 4203.55862634419, 4860.881997140748, 5128.692278463304, 5375.973120134531, 5469.945300546943, 4217.683673724544, 4291.527931927423, 4704.388429504143, 4794.113089078532, 5460.778140896889, 4435.756033418193, 5012.020252000367, 5202.26880887537, 5430.294061771944, 5470.945290547043], "value": 3734.394747473977, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.1134923527854, 839.1063517354017, 840.7183593496178, 844.6368061733452, 858.8282343531291, 14255.144691318515, 15073.39706411745, 15083.547493575197, 15098.169600671987, 15139.091654500739, 11408.885911140886, 13840.446382144703, 13843.169409835416, 13918.0, 13918.930405347985, 718.6370882704227, 749.7713197474768, 749.947503674743, 752.0524139584136, 753.1150897695186, 851.4210336970857, 887.6671248281895, 920.6823645842173, 921.1893533690343, 927.6057675487924, 949.9572519236651, 963.629002833907, 970.4613939263705, 971.8104969530945, 980.1276863591373, 963.9974426596332, 965.7295957132014, 966.8839739231267, 972.2270794718181, 973.6202880876464, 558.0151033426006, 700.9859802803967, 719.316649183278, 723.9312265334785, 726.3172617739308, 14522.782158267635, 14529.709405811824, 14544.854551454457, 14563.980521363508, 14565.053271537316, 13394.526602073764, 13809.24049177295, 13842.584722458343, 13859.861401386059, 13944.51194208198, 11910.166288359818, 11972.58095966637, 12070.27578345302, 12163.574274900337, 12691.238525688395, 4473.530279320689, 4480.686351955364, 4586.701864378805, 4653.441587009546, 4658.208104622222, 76299.6185019077, 77714.44571108602, 78167.43665126723, 78589.0, 79078.60460697717, 9018.0, 10861.456927153611, 10877.129829613612, 10912.836307455394, 10999.285046471954, 20958.580828383347, 20971.951402430124, 21014.054367553497, 21018.054187561596, 21045.0, 7709.151993280735, 7774.883376749353, 7803.453758236869, 7856.764297071097, 7965.960170199228, 4292.334688123346, 4294.119705460374, 4359.782010899442, 4374.081442896963, 4471.61991230749, 2848.7293707097792, 3110.6578276389578, 3417.5386322846593, 3425.8629654814026, 3430.142464383904, 652.7845810882397, 657.2934095846983, 668.4919461209515, 679.2629996453804, 689.2039694153261, 615.3385111005687, 624.2122917224896, 639.9008153736179, 666.167290886392, 677.2178134254845, 8710.608022638997, 8734.781630459289, 8845.911540884508, 8850.645974160961, 8963.596638151299, 629.5907660020931, 650.9511786616023, 658.716751796732, 668.3617145625916, 676.1851968378093, 4150.91698166035, 4172.415861779354, 4194.706370554032, 4215.641670457984, 4377.139715620849, 86639.56680216559, 87132.56433717918, 88114.0, 89486.26164860258, 91266.0, 76704.0, 78530.0, 79083.60458197787, 79779.60110199412, 82376.58811705903, 69316.306836932, 69584.0, 69904.65047674831, 70583.58832823315, 70870.64564677143, 8156.796080097808, 8226.547059435348, 8230.588470576327, 8269.95865020671, 8323.084460709197, 39442.802785985885, 41928.74213773591, 43839.0, 43982.12035759203, 44172.779136104116, 60247.0, 60610.0, 60865.39134608597, 60921.08618370639, 61147.0, 11123.944380278046, 11181.664550063511, 11278.774424511299, 11404.315741055563, 11823.290602563873, 9535.570899309687, 11810.114241432098, 11882.940585297018, 11883.405829708307, 12246.510139594317, 11381.601643942422, 11387.715307117389, 11441.199116061796, 11689.590864319707, 11836.763264734485, 11372.886271137182, 11481.655550333504, 11508.59719909799, 11600.593979210687, 11850.703732406759, 11046.613368532384, 11106.61126860556, 11135.0, 11185.32888026721, 11264.943675281898, 9496.715098547054, 9654.179394751525, 9700.75748106303, 9991.25065620067, 9998.800023999625, 9433.433993960385, 9598.856017159887, 9685.515724213901, 9837.213022958003, 9956.800863982824], "value": 7027.172078688632, "unit": "score"}], "name": "dromaeo_css", "value": 7081.118139438292, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 109.0, 111.0, 112.0, 112.0, 112.0, 112.0, 111.0, 112.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 120.0, 122.0, 196.0, 106.0, 130.0, 166.0, 139.0, 157.0, 122.0], "value": 141.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 182.0, 181.0, 182.0, 182.0, 233.0, 229.0, 181.0, 182.0, 182.0], "value": 191.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 59.0, 115.0, 59.0, 83.0, 85.0, 82.0, 112.0, 55.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 116.0, 126.0, 56.0, 83.0, 98.0, 127.0, 100.0, 67.0, 100.0], "value": 96.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 87.0, 84.0, 87.0, 87.0, 134.0, 84.0, 87.0, 85.0], "value": 90.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 97.0, 93.0, 93.0, 93.0, 93.0, 93.0, 92.0], "value": 93.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 133.0, 85.0, 84.0, 82.0, 84.0, 87.0, 84.0, 86.0, 87.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 55.0, 55.0, 55.0, 55.0, 56.0, 54.0, 55.0, 55.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 70.0, 82.0, 75.0, 71.0, 71.0, 69.0, 70.0, 69.0, 69.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 85.0, 80.0, 80.0, 84.0, 86.0, 141.0, 86.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 140.0, 139.0, 135.0, 138.0, 140.0, 140.0, 142.0, 164.0, 139.0], "value": 141.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 166.0, 166.0, 179.0, 216.0, 221.0, 166.0, 161.0, 162.0], "value": 176.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 65.0, 66.0, 66.0, 67.0, 66.0, 66.0, 63.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1498.1, "alertThreshold": 2.0}]} 20:37:39 INFO - Return code: 0 20:37:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 20:37:39 ERROR - # TBPL SUCCESS # 20:37:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:37:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:37:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:37:39 INFO - 2016-05-29 20:37:39.629 screenresolution[1679:10766] starting screenresolution argv=screenresolution get 20:37:39 INFO - 2016-05-29 20:37:39.631 screenresolution[1679:10766] Display 0: 1600x1200x32@60 20:37:39 INFO - 2016-05-29 20:37:39.637 screenresolution[1680:10769] starting screenresolution argv=screenresolution list 20:37:39 INFO - Available Modes on Display 0 20:37:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:37:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:37:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:37:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:37:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:37:39 INFO - Intel Iris: 20:37:39 INFO - Chipset Model: Intel Iris 20:37:39 INFO - Type: GPU 20:37:39 INFO - Bus: Built-In 20:37:39 INFO - VRAM (Dynamic, Max): 1536 MB 20:37:39 INFO - Vendor: Intel (0x8086) 20:37:39 INFO - Device ID: 0x0a2e 20:37:39 INFO - Revision ID: 0x0009 20:37:39 INFO - Displays: 20:37:39 INFO - Display: 20:37:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:37:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:37:39 INFO - Display Serial Number: 1600x1200 60 20:37:39 INFO - Main Display: Yes 20:37:39 INFO - Mirror: Off 20:37:39 INFO - Online: Yes 20:37:39 INFO - Rotation: Supported 20:37:39 INFO - Return code: 0 20:37:39 INFO - Running post-action listener: _resource_record_post_action 20:37:39 INFO - ##### 20:37:39 INFO - ##### Finished run-tests step (success) 20:37:39 INFO - ##### 20:37:39 INFO - Running post-run listener: _resource_record_post_run 20:37:39 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 31534592; Write bytes: 694873600; Read time: 200; Write time: 1920 20:37:39 INFO - TinderboxPrint: CPU usage
53.9% 20:37:39 INFO - TinderboxPrint: I/O read bytes / time
31,534,592 / 200 20:37:39 INFO - TinderboxPrint: I/O write bytes / time
694,873,600 / 1,920 20:37:39 INFO - TinderboxPrint: CPU idle
2,233.2 (46.1%) 20:37:39 INFO - TinderboxPrint: CPU system
963.8 (19.9%) 20:37:39 INFO - TinderboxPrint: CPU user
1,644.7 (34.0%) 20:37:39 INFO - TinderboxPrint: Swap in / out
425,787,392 / 0 20:37:39 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398991872; Write bytes: 401062912; Read time: 30049; Write time: 843 20:37:39 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 29736448; Write bytes: 292962816; Read time: 183; Write time: 1074 20:37:40 INFO - Running post-run listener: _upload_blobber_files 20:37:40 INFO - Blob upload gear active. 20:37:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:37:40 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 20:37:40 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'] 20:37:40 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 20:37:40 INFO - (blobuploader) - INFO - Open directory for files ... 20:37:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:37:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:37:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:37:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:37:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:37:41 INFO - (blobuploader) - INFO - Done attempting. 20:37:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:37:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:37:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:37:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:37:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:37:42 INFO - (blobuploader) - INFO - Done attempting. 20:37:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:37:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:37:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:37:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:37:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:37:45 INFO - (blobuploader) - INFO - Done attempting. 20:37:45 INFO - (blobuploader) - INFO - Iteration through files over. 20:37:45 INFO - Return code: 0 20:37:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:37:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:37:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd129097f5fe48733d08b60649a6b7a35295745e43178dff0463d2e9e77788f205339c58ac32a00d7a255aaef444034552515ed346f29baa0a28b6dac9fa9245", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c85f90b36168ad8f87aebaf951755208fc935c812ec9982ffa35a84c8b9a04ea37ceddf659cf335bd3fae1ccf99072f287c403b9c24f012cf95a783d8d8e384c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/371eb181c8215a721234be99eaf8c1e041567c169d9ca5d5c888270281dfecf46dc5e15d3f730d08c4df63857338dbf5541a94fc34f05760a39229aaaebb11e7"} 20:37:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:37:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:37:45 INFO - Contents: 20:37:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dd129097f5fe48733d08b60649a6b7a35295745e43178dff0463d2e9e77788f205339c58ac32a00d7a255aaef444034552515ed346f29baa0a28b6dac9fa9245", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c85f90b36168ad8f87aebaf951755208fc935c812ec9982ffa35a84c8b9a04ea37ceddf659cf335bd3fae1ccf99072f287c403b9c24f012cf95a783d8d8e384c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/371eb181c8215a721234be99eaf8c1e041567c169d9ca5d5c888270281dfecf46dc5e15d3f730d08c4df63857338dbf5541a94fc34f05760a39229aaaebb11e7"} 20:37:45 INFO - Running post-run listener: copy_logs_to_upload_dir 20:37:45 INFO - Copying logs to upload dir... 20:37:45 INFO - mkdir: /builds/slave/test/build/upload/logs 20:37:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.433891 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-05-29 20:37:45.341632) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-29 20:37:45.345344) ========= 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.wSdJiKNFBu/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.7bXbA5SHk8/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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/dd129097f5fe48733d08b60649a6b7a35295745e43178dff0463d2e9e77788f205339c58ac32a00d7a255aaef444034552515ed346f29baa0a28b6dac9fa9245", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c85f90b36168ad8f87aebaf951755208fc935c812ec9982ffa35a84c8b9a04ea37ceddf659cf335bd3fae1ccf99072f287c403b9c24f012cf95a783d8d8e384c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/371eb181c8215a721234be99eaf8c1e041567c169d9ca5d5c888270281dfecf46dc5e15d3f730d08c4df63857338dbf5541a94fc34f05760a39229aaaebb11e7"} build_url:https://queue.taskcluster.net/v1/task/P8FDQ398Q6qyIlBvCE4osw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011262 build_url: 'https://queue.taskcluster.net/v1/task/P8FDQ398Q6qyIlBvCE4osw/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/dd129097f5fe48733d08b60649a6b7a35295745e43178dff0463d2e9e77788f205339c58ac32a00d7a255aaef444034552515ed346f29baa0a28b6dac9fa9245", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c85f90b36168ad8f87aebaf951755208fc935c812ec9982ffa35a84c8b9a04ea37ceddf659cf335bd3fae1ccf99072f287c403b9c24f012cf95a783d8d8e384c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/371eb181c8215a721234be99eaf8c1e041567c169d9ca5d5c888270281dfecf46dc5e15d3f730d08c4df63857338dbf5541a94fc34f05760a39229aaaebb11e7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-29 20:37:45.376152) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 20:37:45.376477) ========= 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.wSdJiKNFBu/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.7bXbA5SHk8/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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.005172 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-29 20:37:45.425748) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-29 20:37:45.426131) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-29 20:37:45.426465) ========= ========= Total master_lag: 0.24 =========