builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0349 starttime: 1463526851.19 results: success (0) buildid: 20160517142139 builduid: dc3b86f2bd2844a1906d7f695017ebc0 revision: 0fff370636c2b5ff1ac268b6d3d34cd95ba2068b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 16:14:11.193874) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-17 16:14:11.194474) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 16:14:11.194792) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-17 16:14:11.222020) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 16:14:11.222375) ========= 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.agQUoLLMj4/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.RPvnWZ5C9w/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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-17 16:14:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.3M=0.001s 2016-05-17 16:14:11 (13.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.509986 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 16:14:11.750783) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 16:14:11.751271) ========= 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.agQUoLLMj4/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.RPvnWZ5C9w/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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.025461 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 16:14:11.800638) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 16:14:11.800986) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0fff370636c2b5ff1ac268b6d3d34cd95ba2068b --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 0fff370636c2b5ff1ac268b6d3d34cd95ba2068b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.agQUoLLMj4/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.RPvnWZ5C9w/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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-17 16:14:11,880 truncating revision to first 12 chars 2016-05-17 16:14:11,880 Setting DEBUG logging. 2016-05-17 16:14:11,880 attempt 1/10 2016-05-17 16:14:11,880 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0fff370636c2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-17 16:14:13,064 unpacking tar archive at: fx-team-0fff370636c2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.433967 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-17 16:14:13.252554) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 16:14:13.252865) ========= script_repo_revision: 0fff370636c2b5ff1ac268b6d3d34cd95ba2068b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-17 16:14:13.253281) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 16:14:13.253592) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-17 16:14:13.269317) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 34 secs) (at 2016-05-17 16:14:13.269756) ========= /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.agQUoLLMj4/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.RPvnWZ5C9w/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 16:14:13 INFO - MultiFileLogger online at 20160517 16:14:13 in /builds/slave/test 16:14:13 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 16:14:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:14:13 INFO - {'append_to_log': False, 16:14:13 INFO - 'base_work_dir': '/builds/slave/test', 16:14:13 INFO - 'blob_upload_branch': 'Fx-Team', 16:14:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:14:13 INFO - 'branch': 'Fx-Team', 16:14:13 INFO - 'buildbot_json_path': 'buildprops.json', 16:14:13 INFO - 'config_files': ('talos/mac_config.py',), 16:14:13 INFO - 'default_actions': ('clobber', 16:14:13 INFO - 'read-buildbot-config', 16:14:13 INFO - 'download-and-extract', 16:14:13 INFO - 'populate-webroot', 16:14:13 INFO - 'create-virtualenv', 16:14:13 INFO - 'install', 16:14:13 INFO - 'run-tests'), 16:14:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:14:13 INFO - 'download_minidump_stackwalk': True, 16:14:13 INFO - 'download_symbols': 'ondemand', 16:14:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:14:13 INFO - 'tooltool.py': '/tools/tooltool.py', 16:14:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:14:13 INFO - '/tools/misc-python/virtualenv.py')}, 16:14:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:14:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:14:13 INFO - 'installer_path': 'installer.exe', 16:14:13 INFO - 'log_level': 'info', 16:14:13 INFO - 'log_name': 'talos', 16:14:13 INFO - 'log_to_console': True, 16:14:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:14:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:14:13 INFO - 'opt_config_files': (), 16:14:13 INFO - 'pip_index': False, 16:14:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:14:13 INFO - 'cmd': ('bash', 16:14:13 INFO - '-c', 16:14:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:14:13 INFO - 'enabled': True, 16:14:13 INFO - 'halt_on_failure': False, 16:14:13 INFO - 'name': 'check_screen_resolution'},), 16:14:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:14:13 INFO - 'cmd': ('bash', 16:14:13 INFO - '-c', 16:14:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:14:13 INFO - 'enabled': True, 16:14:13 INFO - 'halt_on_failure': False, 16:14:13 INFO - 'name': 'check_screen_resolution'},), 16:14:13 INFO - 'run_cmd_checks_enabled': True, 16:14:13 INFO - 'sps_profile': False, 16:14:13 INFO - 'sps_profile_interval': 0, 16:14:13 INFO - 'suite': 'dromaeojs', 16:14:13 INFO - 'system_bits': '32', 16:14:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:14:13 INFO - 'title': 't-yosemite-r7-0349', 16:14:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:14:13 INFO - 'use_talos_json': True, 16:14:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:14:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:14:13 INFO - 'work_dir': 'build'} 16:14:13 INFO - ##### 16:14:13 INFO - ##### Running clobber step. 16:14:13 INFO - ##### 16:14:13 INFO - Running pre-action listener: _resource_record_pre_action 16:14:13 INFO - Running main action method: clobber 16:14:13 INFO - rmtree: /builds/slave/test/build 16:14:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:14:15 INFO - Running post-action listener: _resource_record_post_action 16:14:15 INFO - ##### 16:14:15 INFO - ##### Finished clobber step (success) 16:14:15 INFO - ##### 16:14:15 INFO - ##### 16:14:15 INFO - ##### Running read-buildbot-config step. 16:14:15 INFO - ##### 16:14:15 INFO - Running pre-action listener: _resource_record_pre_action 16:14:15 INFO - Running main action method: read_buildbot_config 16:14:15 INFO - Using buildbot properties: 16:14:15 INFO - { 16:14:15 INFO - "project": "", 16:14:15 INFO - "product": "firefox", 16:14:15 INFO - "script_repo_revision": "production", 16:14:15 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 16:14:15 INFO - "repository": "", 16:14:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 16:14:15 INFO - "buildid": "20160517142139", 16:14:15 INFO - "pgo_build": "False", 16:14:15 INFO - "basedir": "/builds/slave/test", 16:14:15 INFO - "buildnumber": 12, 16:14:15 INFO - "slavename": "t-yosemite-r7-0349", 16:14:15 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 16:14:15 INFO - "platform": "yosemite_r7", 16:14:15 INFO - "scheduler": "tests-fx-team-macosx64-talos", 16:14:15 INFO - "branch": "fx-team", 16:14:15 INFO - "repo_path": "integration/fx-team", 16:14:15 INFO - "revision": "0fff370636c2b5ff1ac268b6d3d34cd95ba2068b", 16:14:15 INFO - "stage_platform": "macosx64", 16:14:15 INFO - "builduid": "dc3b86f2bd2844a1906d7f695017ebc0", 16:14:15 INFO - "slavebuilddir": "test" 16:14:15 INFO - } 16:14:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 16:14:15 INFO - Running post-action listener: _resource_record_post_action 16:14:15 INFO - ##### 16:14:15 INFO - ##### Finished read-buildbot-config step (success) 16:14:15 INFO - ##### 16:14:15 INFO - ##### 16:14:15 INFO - ##### Running download-and-extract step. 16:14:15 INFO - ##### 16:14:15 INFO - Running pre-action listener: _resource_record_pre_action 16:14:15 INFO - Running main action method: download_and_extract 16:14:15 INFO - mkdir: /builds/slave/test/build/tests 16:14:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:15 INFO - https://queue.taskcluster.net/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:14:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:14:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:14:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/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 16:14:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/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 16:14:30 INFO - Downloaded 1199 bytes. 16:14:30 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 16:14:30 INFO - Using the following test package requirements: 16:14:30 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:14:30 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:14:30 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 16:14:30 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:14:30 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 16:14:30 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:14:30 INFO - u'jsshell-mac.zip'], 16:14:30 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:14:30 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 16:14:30 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:14:30 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:14:30 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 16:14:30 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:14:30 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 16:14:30 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:14:30 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 16:14:30 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:14:30 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 16:14:30 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:30 INFO - https://queue.taskcluster.net/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:14:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:14:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:14:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/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 16:14:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/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 16:14:37 INFO - Downloaded 18268242 bytes. 16:14:37 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'] 16:14:37 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 16:14:40 INFO - Return code: 0 16:14:40 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 16:14:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:40 INFO - https://queue.taskcluster.net/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:14:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:14:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:14:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/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 16:14:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/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 16:14:42 INFO - Downloaded 18268242 bytes. 16:14:42 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'] 16:14:42 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 16:14:45 INFO - Return code: 0 16:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:45 INFO - https://queue.taskcluster.net/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:14:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:14:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:14:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/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 16:14:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/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 16:14:47 INFO - Downloaded 10840194 bytes. 16:14:47 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'] 16:14:47 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 16:14:47 INFO - Return code: 0 16:14: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')]} 16:14:47 INFO - https://queue.taskcluster.net/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:14:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:14:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:14:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 16:14:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PFZJGmDKTtizPf7bkQRIbQ/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 16:14:50 INFO - Downloaded 110148770 bytes. 16:14:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:14:50 INFO - mkdir: /builds/slave/test/properties 16:14:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:14:50 INFO - Writing to file /builds/slave/test/properties/build_url 16:14:50 INFO - Contents: 16:14:50 INFO - build_url:https://queue.taskcluster.net/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:14:52 INFO - Running post-action listener: _resource_record_post_action 16:14:52 INFO - Running post-action listener: set_extra_try_arguments 16:14:52 INFO - ##### 16:14:52 INFO - ##### Finished download-and-extract step (success) 16:14:52 INFO - ##### 16:14:52 INFO - ##### 16:14:52 INFO - ##### Running populate-webroot step. 16:14:52 INFO - ##### 16:14:52 INFO - Running pre-action listener: _resource_record_pre_action 16:14:52 INFO - Running main action method: populate_webroot 16:14:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:14:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:14:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:14:52 INFO - '--tppagecycles', 16:14:52 INFO - '7'], 16:14:52 INFO - 'tests': ['tsvgm']}}, 16:14:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:14:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:14:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:14:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:14:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:14:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:14:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:52 INFO - 'talos_options': ['--disable-e10s'], 16:14:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:14:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:14:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:52 INFO - 'talos_options': ['--disable-e10s'], 16:14:52 INFO - 'tests': ['damp', 'tps']}, 16:14:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:52 INFO - 'tests': ['damp', 'tps']}, 16:14:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:14:52 INFO - 'tests': ['dromaeo_dom']}, 16:14:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:14:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:14:52 INFO - 'tests': ['a11yr', 16:14:52 INFO - 'ts_paint', 16:14:52 INFO - 'tpaint', 16:14:52 INFO - 'sessionrestore', 16:14:52 INFO - 'sessionrestore_no_auto_restore', 16:14:52 INFO - 'tabpaint']}, 16:14:52 INFO - 'other-e10s': {'tests': ['a11yr', 16:14:52 INFO - 'ts_paint', 16:14:52 INFO - 'tpaint', 16:14:52 INFO - 'sessionrestore', 16:14:52 INFO - 'sessionrestore_no_auto_restore', 16:14:52 INFO - 'tabpaint']}, 16:14:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:14:52 INFO - 'ts_paint', 16:14:52 INFO - 'tpaint', 16:14:52 INFO - 'sessionrestore', 16:14:52 INFO - 'sessionrestore_no_auto_restore', 16:14:52 INFO - 'tabpaint']}, 16:14:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:14:52 INFO - 'ts_paint', 16:14:52 INFO - 'tpaint', 16:14:52 INFO - 'sessionrestore', 16:14:52 INFO - 'sessionrestore_no_auto_restore', 16:14:52 INFO - 'tabpaint']}, 16:14:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:14:52 INFO - 'tests': ['a11yr', 16:14:52 INFO - 'ts_paint', 16:14:52 INFO - 'tpaint', 16:14:52 INFO - 'sessionrestore', 16:14:52 INFO - 'sessionrestore_no_auto_restore', 16:14:52 INFO - 'tabpaint']}, 16:14:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:14:52 INFO - 'tests': ['a11yr', 16:14:52 INFO - 'ts_paint', 16:14:52 INFO - 'tpaint', 16:14:52 INFO - 'sessionrestore', 16:14:52 INFO - 'sessionrestore_no_auto_restore', 16:14:52 INFO - 'tabpaint']}, 16:14:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:14:52 INFO - 'tests': ['tsvgx', 16:14:52 INFO - 'tsvgr_opacity', 16:14:52 INFO - 'tart', 16:14:52 INFO - 'tscrollx', 16:14:52 INFO - 'cart']}, 16:14:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:14:52 INFO - 'tsvgr_opacity', 16:14:52 INFO - 'tart', 16:14:52 INFO - 'tscrollx', 16:14:52 INFO - 'cart']}, 16:14:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:52 INFO - 'talos_options': ['--disable-e10s'], 16:14:52 INFO - 'tests': ['tp5o']}, 16:14:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:52 INFO - 'tests': ['tp5o']}, 16:14:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:52 INFO - 'talos_options': ['--disable-e10s', 16:14:52 INFO - '--xperf_path', 16:14:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:14:52 INFO - 'tests': ['tp5n']}, 16:14:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:52 INFO - 'talos_options': ['--xperf_path', 16:14:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:14:52 INFO - 'tests': ['tp5n']}}, 16:14:52 INFO - 'talos.zip': {'path': '', 16:14:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:14:52 INFO - Running post-action listener: _resource_record_post_action 16:14:52 INFO - ##### 16:14:52 INFO - ##### Finished populate-webroot step (success) 16:14:52 INFO - ##### 16:14:52 INFO - ##### 16:14:52 INFO - ##### Running create-virtualenv step. 16:14:52 INFO - ##### 16:14:52 INFO - Running pre-action listener: _resource_record_pre_action 16:14:52 INFO - Running main action method: create_virtualenv 16:14:52 INFO - Creating virtualenv /builds/slave/test/build/venv 16:14:52 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:14:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:14:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:14:52 INFO - Using real prefix '/tools/python27' 16:14:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:14:52 INFO - Installing distribute.............................................................................................................................................................................................done. 16:14:55 INFO - Installing pip.................done. 16:14:55 INFO - Return code: 0 16:14:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:14:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101009a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10100f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf895827d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eadd30>, '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 0x100cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ea4b20>, '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.agQUoLLMj4/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.RPvnWZ5C9w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 16:14:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:14:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:14:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agQUoLLMj4/Render', 16:14:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:55 INFO - 'HOME': '/Users/cltbld', 16:14:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:55 INFO - 'LOGNAME': 'cltbld', 16:14:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:55 INFO - 'MOZ_NO_REMOTE': '1', 16:14:55 INFO - 'NO_EM_RESTART': '1', 16:14:55 INFO - 'PAGER': '/bin/cat', 16:14:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:55 INFO - 'PWD': '/builds/slave/test', 16:14:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:55 INFO - 'SHELL': '/bin/bash', 16:14:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RPvnWZ5C9w/Listeners', 16:14:55 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:14:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:55 INFO - 'USER': 'cltbld', 16:14:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:55 INFO - 'XPC_FLAGS': '0x0', 16:14:55 INFO - 'XPC_SERVICE_NAME': '0', 16:14:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:55 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 16:14:55 INFO - Cleaning up... 16:14:55 INFO - Return code: 0 16:14:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:14:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101009a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10100f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf895827d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eadd30>, '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 0x100cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ea4b20>, '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.agQUoLLMj4/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.RPvnWZ5C9w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 16:14:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:14:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:14:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agQUoLLMj4/Render', 16:14:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:56 INFO - 'HOME': '/Users/cltbld', 16:14:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:56 INFO - 'LOGNAME': 'cltbld', 16:14:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:56 INFO - 'MOZ_NO_REMOTE': '1', 16:14:56 INFO - 'NO_EM_RESTART': '1', 16:14:56 INFO - 'PAGER': '/bin/cat', 16:14:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:56 INFO - 'PWD': '/builds/slave/test', 16:14:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:56 INFO - 'SHELL': '/bin/bash', 16:14:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RPvnWZ5C9w/Listeners', 16:14:56 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:14:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:56 INFO - 'USER': 'cltbld', 16:14:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:56 INFO - 'XPC_FLAGS': '0x0', 16:14:56 INFO - 'XPC_SERVICE_NAME': '0', 16:14:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:56 INFO - Downloading/unpacking psutil>=3.1.1 16:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:14:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:14:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:14:59 INFO - Installing collected packages: psutil 16:14:59 INFO - Running setup.py install for psutil 16:14:59 INFO - building 'psutil._psutil_osx' extension 16:14:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:15:00 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 16:15:00 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 16:15:00 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 16:15:00 INFO - building 'psutil._psutil_posix' extension 16:15:00 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 16:15:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:15:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:15:00 INFO - ^ 16:15:00 INFO - 1 warning generated. 16:15:00 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 16:15:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:15:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:15:00 INFO - Successfully installed psutil 16:15:00 INFO - Cleaning up... 16:15:00 INFO - Return code: 0 16:15:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:15:00 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')]} 16:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:00 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')]} 16:15:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:00 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 0x101009a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10100f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf895827d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eadd30>, '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 0x100cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ea4b20>, '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.agQUoLLMj4/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.RPvnWZ5C9w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 16:15:00 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 16:15:00 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 16:15:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agQUoLLMj4/Render', 16:15:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:00 INFO - 'HOME': '/Users/cltbld', 16:15:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:00 INFO - 'LOGNAME': 'cltbld', 16:15:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:00 INFO - 'MOZ_NO_REMOTE': '1', 16:15:00 INFO - 'NO_EM_RESTART': '1', 16:15:00 INFO - 'PAGER': '/bin/cat', 16:15:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:00 INFO - 'PWD': '/builds/slave/test', 16:15:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:00 INFO - 'SHELL': '/bin/bash', 16:15:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RPvnWZ5C9w/Listeners', 16:15:00 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:15:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:00 INFO - 'USER': 'cltbld', 16:15:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:00 INFO - 'XPC_FLAGS': '0x0', 16:15:00 INFO - 'XPC_SERVICE_NAME': '0', 16:15:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:15:01 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 16:15:01 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 16:15:01 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 16:15:01 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 16:15:01 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 16:15:01 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 16:15:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:15:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:15:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:15:03 INFO - Installing collected packages: mozsystemmonitor 16:15:03 INFO - Running setup.py install for mozsystemmonitor 16:15:03 INFO - Successfully installed mozsystemmonitor 16:15:03 INFO - Cleaning up... 16:15:03 INFO - Return code: 0 16:15:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:15:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101009a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10100f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf895827d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eadd30>, '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 0x100cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ea4b20>, '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.agQUoLLMj4/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.RPvnWZ5C9w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 16:15:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:15:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:15:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agQUoLLMj4/Render', 16:15:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:03 INFO - 'HOME': '/Users/cltbld', 16:15:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:03 INFO - 'LOGNAME': 'cltbld', 16:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:03 INFO - 'MOZ_NO_REMOTE': '1', 16:15:03 INFO - 'NO_EM_RESTART': '1', 16:15:03 INFO - 'PAGER': '/bin/cat', 16:15:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:03 INFO - 'PWD': '/builds/slave/test', 16:15:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:03 INFO - 'SHELL': '/bin/bash', 16:15:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RPvnWZ5C9w/Listeners', 16:15:03 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:15:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:03 INFO - 'USER': 'cltbld', 16:15:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:03 INFO - 'XPC_FLAGS': '0x0', 16:15:03 INFO - 'XPC_SERVICE_NAME': '0', 16:15:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:04 INFO - Downloading/unpacking blobuploader==1.2.4 16:15:04 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 16:15:04 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 16:15:04 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 16:15:04 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 16:15:04 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 16:15:04 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 16:15:06 INFO - Downloading blobuploader-1.2.4.tar.gz 16:15:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:15:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:15:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:15:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:15:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:15:07 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 16:15:07 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 16:15:07 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 16:15:07 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 16:15:07 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 16:15:07 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 16:15:07 INFO - Downloading docopt-0.6.1.tar.gz 16:15:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:15:07 INFO - Installing collected packages: blobuploader, requests, docopt 16:15:07 INFO - Running setup.py install for blobuploader 16:15:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:15:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:15:07 INFO - Running setup.py install for requests 16:15:08 INFO - Running setup.py install for docopt 16:15:08 INFO - Successfully installed blobuploader requests docopt 16:15:08 INFO - Cleaning up... 16:15:08 INFO - Return code: 0 16:15:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15:08 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')]} 16:15:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:08 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')]} 16:15:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:08 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 0x101009a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10100f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf895827d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eadd30>, '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 0x100cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ea4b20>, '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.agQUoLLMj4/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.RPvnWZ5C9w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 16:15:08 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 16:15:08 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 16:15:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agQUoLLMj4/Render', 16:15:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:08 INFO - 'HOME': '/Users/cltbld', 16:15:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:08 INFO - 'LOGNAME': 'cltbld', 16:15:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:08 INFO - 'MOZ_NO_REMOTE': '1', 16:15:08 INFO - 'NO_EM_RESTART': '1', 16:15:08 INFO - 'PAGER': '/bin/cat', 16:15:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:08 INFO - 'PWD': '/builds/slave/test', 16:15:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:08 INFO - 'SHELL': '/bin/bash', 16:15:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RPvnWZ5C9w/Listeners', 16:15:08 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:15:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:08 INFO - 'USER': 'cltbld', 16:15:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:08 INFO - 'XPC_FLAGS': '0x0', 16:15:08 INFO - 'XPC_SERVICE_NAME': '0', 16:15:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:15:08 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-dfVcmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:15:09 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-fwFb8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:15:09 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-evfZ_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:15:09 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-nZpuHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:15:09 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-hNx74X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:15:09 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-ubVQta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:15:09 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-LBVBEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:15:09 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-16em9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:15:09 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-cgb3kq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:15:09 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-27wtPb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:15:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:15:09 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-0LwljP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:15:10 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-h6S3ps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:15:10 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-ykucDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:15:10 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-7P9YtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:10 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-7vQAUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:15:10 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-gIAzde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:15:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:15:10 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-y_B_nl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:15:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:15:10 INFO - Running setup.py install for manifestparser 16:15:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:15:10 INFO - Running setup.py install for mozcrash 16:15:11 INFO - Running setup.py install for mozdebug 16:15:11 INFO - Running setup.py install for mozdevice 16:15:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:15:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:15:11 INFO - Running setup.py install for mozfile 16:15:11 INFO - Running setup.py install for mozhttpd 16:15:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:15:11 INFO - Running setup.py install for mozinfo 16:15:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:15:11 INFO - Running setup.py install for mozInstall 16:15:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:15:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:15:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:15:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:15:11 INFO - Running setup.py install for mozleak 16:15:12 INFO - Running setup.py install for mozlog 16:15:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:15:12 INFO - Running setup.py install for moznetwork 16:15:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:15:12 INFO - Running setup.py install for mozprocess 16:15:12 INFO - Running setup.py install for mozprofile 16:15:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:15:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:15:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:15:12 INFO - Running setup.py install for mozrunner 16:15:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:15:12 INFO - Running setup.py install for mozscreenshot 16:15:13 INFO - Running setup.py install for moztest 16:15:13 INFO - Running setup.py install for mozversion 16:15:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:15:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:15:13 INFO - Cleaning up... 16:15:13 INFO - Return code: 0 16:15:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15:13 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')]} 16:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:13 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')]} 16:15:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:13 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 0x101009a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10100f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf895827d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eadd30>, '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 0x100cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ea4b20>, '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.agQUoLLMj4/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.RPvnWZ5C9w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 16:15:13 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 16:15:13 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 16:15:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agQUoLLMj4/Render', 16:15:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:13 INFO - 'HOME': '/Users/cltbld', 16:15:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:13 INFO - 'LOGNAME': 'cltbld', 16:15:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:13 INFO - 'MOZ_NO_REMOTE': '1', 16:15:13 INFO - 'NO_EM_RESTART': '1', 16:15:13 INFO - 'PAGER': '/bin/cat', 16:15:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:13 INFO - 'PWD': '/builds/slave/test', 16:15:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:13 INFO - 'SHELL': '/bin/bash', 16:15:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RPvnWZ5C9w/Listeners', 16:15:13 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:15:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:13 INFO - 'USER': 'cltbld', 16:15:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:13 INFO - 'XPC_FLAGS': '0x0', 16:15:13 INFO - 'XPC_SERVICE_NAME': '0', 16:15:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:15:13 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-APxeDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:15:13 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)) 16:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:15:13 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-Fu2wJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:15:13 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)) 16:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:15:13 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-B2S7xp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:15:13 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)) 16:15:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:15:13 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-TJMdTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:15:14 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)) 16:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:15:14 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-CaBG0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:15:14 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)) 16:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:15:14 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-VJnCRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:15:14 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)) 16:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:15:14 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-4Zpxwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:15:14 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)) 16:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:15:14 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-p2t7gs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:15:14 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)) 16:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:15:14 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-lzsMo8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:15:14 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)) 16:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:15:14 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-Ew3EGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:15:14 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)) 16:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:15:14 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-4yOcJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:15:14 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)) 16:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:15:14 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-RN4Cu0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:15:14 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)) 16:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:15:14 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-94Vm5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:15:14 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)) 16:15:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:15:15 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-l_6b8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:15:15 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)) 16:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:15 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-ZmfbbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:15:15 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)) 16:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:15:15 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-4LqPQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:15:15 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)) 16:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:15:15 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-sIWHbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:15:15 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)) 16:15:15 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)) 16:15:15 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)) 16:15:15 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)) 16:15:15 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)) 16:15:15 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)) 16:15:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:15:15 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 16:15:15 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 16:15:15 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 16:15:15 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 16:15:15 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 16:15:15 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 16:15:18 INFO - Downloading blessings-1.6.tar.gz 16:15:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:15:18 INFO - Installing collected packages: blessings 16:15:18 INFO - Running setup.py install for blessings 16:15:18 INFO - Successfully installed blessings 16:15:18 INFO - Cleaning up... 16:15:18 INFO - Return code: 0 16:15:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:15:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:15:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:15:18 INFO - Reading from file tmpfile_stdout 16:15:18 INFO - Current package versions: 16:15:18 INFO - blessings == 1.6 16:15:18 INFO - blobuploader == 1.2.4 16:15:18 INFO - docopt == 0.6.1 16:15:18 INFO - manifestparser == 1.1 16:15:18 INFO - mozInstall == 1.12 16:15:18 INFO - mozcrash == 0.17 16:15:18 INFO - mozdebug == 0.1 16:15:18 INFO - mozdevice == 0.48 16:15:18 INFO - mozfile == 1.2 16:15:18 INFO - mozhttpd == 0.7 16:15:18 INFO - mozinfo == 0.9 16:15:18 INFO - mozleak == 0.1 16:15:18 INFO - mozlog == 3.2 16:15:18 INFO - moznetwork == 0.27 16:15:18 INFO - mozprocess == 0.22 16:15:18 INFO - mozprofile == 0.28 16:15:18 INFO - mozrunner == 6.11 16:15:18 INFO - mozscreenshot == 0.1 16:15:18 INFO - mozsystemmonitor == 0.3 16:15:18 INFO - moztest == 0.7 16:15:18 INFO - mozversion == 1.4 16:15:18 INFO - psutil == 3.1.1 16:15:18 INFO - requests == 1.2.3 16:15:18 INFO - wsgiref == 0.1.2 16:15:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:15:18 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')]} 16:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:19 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')]} 16:15:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:19 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 0x101009a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10100f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf895827d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eadd30>, '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 0x100cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ea4b20>, '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.agQUoLLMj4/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.RPvnWZ5C9w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 16:15:19 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 16:15:19 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 16:15:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agQUoLLMj4/Render', 16:15:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:19 INFO - 'HOME': '/Users/cltbld', 16:15:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:19 INFO - 'LOGNAME': 'cltbld', 16:15:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:19 INFO - 'MOZ_NO_REMOTE': '1', 16:15:19 INFO - 'NO_EM_RESTART': '1', 16:15:19 INFO - 'PAGER': '/bin/cat', 16:15:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:19 INFO - 'PWD': '/builds/slave/test', 16:15:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:19 INFO - 'SHELL': '/bin/bash', 16:15:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RPvnWZ5C9w/Listeners', 16:15:19 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:15:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:19 INFO - 'USER': 'cltbld', 16:15:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:19 INFO - 'XPC_FLAGS': '0x0', 16:15:19 INFO - 'XPC_SERVICE_NAME': '0', 16:15:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:19 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)) 16:15:19 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)) 16:15:19 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)) 16:15:19 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)) 16:15:19 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)) 16:15:19 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)) 16:15:19 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)) 16:15:19 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)) 16:15:19 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)) 16:15:19 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)) 16:15:19 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)) 16:15:19 INFO - Cleaning up... 16:15:19 INFO - Return code: 0 16:15:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:15:19 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')]} 16:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:19 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')]} 16:15:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:19 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 0x101009a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10100f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf895827d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eadd30>, '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 0x100cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ea4b20>, '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.agQUoLLMj4/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.RPvnWZ5C9w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 16:15:19 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 16:15:19 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 16:15:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agQUoLLMj4/Render', 16:15:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:19 INFO - 'HOME': '/Users/cltbld', 16:15:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:19 INFO - 'LOGNAME': 'cltbld', 16:15:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:19 INFO - 'MOZ_NO_REMOTE': '1', 16:15:19 INFO - 'NO_EM_RESTART': '1', 16:15:19 INFO - 'PAGER': '/bin/cat', 16:15:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:19 INFO - 'PWD': '/builds/slave/test', 16:15:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:19 INFO - 'SHELL': '/bin/bash', 16:15:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RPvnWZ5C9w/Listeners', 16:15:19 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:15:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:19 INFO - 'USER': 'cltbld', 16:15:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:19 INFO - 'XPC_FLAGS': '0x0', 16:15:19 INFO - 'XPC_SERVICE_NAME': '0', 16:15:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:19 INFO - Downloading/unpacking jsonschema 16:15:19 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 16:15:19 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 16:15:19 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 16:15:19 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 16:15:19 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 16:15:19 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 16:15:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:15:22 INFO - Downloading/unpacking functools32 (from jsonschema) 16:15:22 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 16:15:22 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 16:15:22 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 16:15:22 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 16:15:22 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 16:15:22 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 16:15:22 INFO - Downloading functools32-3.2.3-2.tar.gz 16:15:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:15:23 INFO - warning: no files found matching '*.txt' 16:15:23 INFO - no previously-included directories found matching 'build' 16:15:23 INFO - no previously-included directories found matching 'dist' 16:15:23 INFO - no previously-included directories found matching '.git*' 16:15:23 INFO - Installing collected packages: jsonschema, functools32 16:15:23 INFO - Running setup.py install for functools32 16:15:23 INFO - warning: no files found matching '*.txt' 16:15:23 INFO - no previously-included directories found matching 'build' 16:15:23 INFO - no previously-included directories found matching 'dist' 16:15:23 INFO - no previously-included directories found matching '.git*' 16:15:23 INFO - Successfully installed jsonschema functools32 16:15:23 INFO - Cleaning up... 16:15:23 INFO - Return code: 0 16:15:23 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:15:23 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')]} 16:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:15:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:23 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')]} 16:15:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:15:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:15:23 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 0x101009a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10100f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf895827d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100eadd30>, '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 0x100cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ea4b20>, '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.agQUoLLMj4/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.RPvnWZ5C9w/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 16:15:23 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 16:15:23 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 16:15:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agQUoLLMj4/Render', 16:15:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:15:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:15:23 INFO - 'HOME': '/Users/cltbld', 16:15:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:15:23 INFO - 'LOGNAME': 'cltbld', 16:15:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:23 INFO - 'MOZ_NO_REMOTE': '1', 16:15:23 INFO - 'NO_EM_RESTART': '1', 16:15:23 INFO - 'PAGER': '/bin/cat', 16:15:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:15:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:15:23 INFO - 'PWD': '/builds/slave/test', 16:15:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:15:23 INFO - 'SHELL': '/bin/bash', 16:15:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RPvnWZ5C9w/Listeners', 16:15:23 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:15:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:15:23 INFO - 'USER': 'cltbld', 16:15:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:15:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:15:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:23 INFO - 'XPC_FLAGS': '0x0', 16:15:23 INFO - 'XPC_SERVICE_NAME': '0', 16:15:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:15:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:15:23 INFO - Downloading/unpacking flake8 16:15: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 16:15: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 16:15: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 16:15: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 16:15: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 16:15: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 16:15:25 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:15:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:15:25 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 16:15:25 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 16:15:25 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 16:15:25 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 16:15:25 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 16:15:25 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 16:15:26 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:15:26 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:15: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 16:15: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 16:15: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 16:15: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 16:15: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 16:15: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 16:15:26 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:15: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 16:15: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 16:15: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 16:15: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 16:15: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 16:15: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 16:15:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:15:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:15:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:15:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:15:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:15:27 INFO - no previously-included directories found matching 'docs/_build' 16:15:27 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:15:27 INFO - Running setup.py install for pep8 16:15:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:15:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:15:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:15:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:15:27 INFO - no previously-included directories found matching 'docs/_build' 16:15:27 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:15:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:15:27 INFO - Cleaning up... 16:15:27 INFO - Return code: 0 16:15:27 INFO - Running post-action listener: _resource_record_post_action 16:15:27 INFO - Running post-action listener: _start_resource_monitoring 16:15:27 INFO - Starting resource monitoring. 16:15:27 INFO - ##### 16:15:27 INFO - ##### Finished create-virtualenv step (success) 16:15:27 INFO - ##### 16:15:27 INFO - ##### 16:15:27 INFO - ##### Running install step. 16:15:27 INFO - ##### 16:15:27 INFO - Running pre-action listener: _resource_record_pre_action 16:15:27 INFO - Running main action method: install 16:15:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:15:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:15:27 INFO - Reading from file tmpfile_stdout 16:15:27 INFO - Detecting whether we're running mozinstall >=1.0... 16:15:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:15:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:15:27 INFO - Reading from file tmpfile_stdout 16:15:27 INFO - Output received: 16:15:27 INFO - Usage: mozinstall [options] installer 16:15:27 INFO - Options: 16:15:27 INFO - -h, --help show this help message and exit 16:15:27 INFO - -d DEST, --destination=DEST 16:15:27 INFO - Directory to install application into. [default: 16:15:27 INFO - "/builds/slave/test"] 16:15:27 INFO - --app=APP Application being installed. [default: firefox] 16:15:27 INFO - mkdir: /builds/slave/test/build/application 16:15:27 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'] 16:15:27 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 16:16:02 INFO - Reading from file tmpfile_stdout 16:16:02 INFO - Output received: 16:16:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:16:02 INFO - Running post-action listener: _resource_record_post_action 16:16:02 INFO - ##### 16:16:02 INFO - ##### Finished install step (success) 16:16:02 INFO - ##### 16:16:02 INFO - ##### 16:16:02 INFO - ##### Running run-tests step. 16:16:02 INFO - ##### 16:16:02 INFO - Running pre-action listener: _resource_record_pre_action 16:16:02 INFO - Running main action method: run_tests 16:16:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:16:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:16:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:16:02 INFO - 2016-05-17 16:16:02.163 screenresolution[1646:7556] starting screenresolution argv=screenresolution get 16:16:02 INFO - 2016-05-17 16:16:02.165 screenresolution[1646:7556] Display 0: 1600x1200x32@60 16:16:02 INFO - 2016-05-17 16:16:02.172 screenresolution[1647:7558] starting screenresolution argv=screenresolution list 16:16:02 INFO - Available Modes on Display 0 16:16:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:16:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:16:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:16:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:16:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:16:02 INFO - Intel Iris: 16:16:02 INFO - Chipset Model: Intel Iris 16:16:02 INFO - Type: GPU 16:16:02 INFO - Bus: Built-In 16:16:02 INFO - VRAM (Dynamic, Max): 1536 MB 16:16:02 INFO - Vendor: Intel (0x8086) 16:16:02 INFO - Device ID: 0x0a2e 16:16:02 INFO - Revision ID: 0x0009 16:16:02 INFO - Displays: 16:16:02 INFO - Display: 16:16:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:16:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:16:02 INFO - Display Serial Number: 1600x1200 60 16:16:02 INFO - Main Display: Yes 16:16:02 INFO - Mirror: Off 16:16:02 INFO - Online: Yes 16:16:02 INFO - Rotation: Supported 16:16:02 INFO - Return code: 0 16:16:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:16:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:16:02 INFO - Python 2.7.3 16:16:02 INFO - Return code: 0 16:16:02 INFO - grabbing minidump binary from tooltool 16:16: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')]} 16:16: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 0x100eadd30>, '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 0x100cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100ea4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:16: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 16:16: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 16:16: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 16:16:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:16:02 INFO - Return code: 0 16:16:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:16:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:16:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:16:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:16:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:16:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:16:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:16:03 INFO - Return code: 0 16:16:03 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/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0349', '--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 16:16:03 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/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0349 --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 16:16:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.agQUoLLMj4/Render', 16:16:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:16:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:16:03 INFO - 'HOME': '/Users/cltbld', 16:16:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:16:03 INFO - 'LOGNAME': 'cltbld', 16:16:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:16:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:03 INFO - 'MOZ_NO_REMOTE': '1', 16:16:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:16:03 INFO - 'NO_EM_RESTART': '1', 16:16:03 INFO - 'PAGER': '/bin/cat', 16:16:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:03 INFO - 'PWD': '/builds/slave/test', 16:16:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:16:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:16:03 INFO - 'SHELL': '/bin/bash', 16:16:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RPvnWZ5C9w/Listeners', 16:16:03 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 16:16:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:16:03 INFO - 'USER': 'cltbld', 16:16:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:16:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:03 INFO - 'XPC_FLAGS': '0x0', 16:16:03 INFO - 'XPC_SERVICE_NAME': '0', 16:16:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:16:03 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/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0349', '--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 16:16:03 INFO - mozversion application_buildid: 20160517142139 16:16:03 INFO - mozversion application_changeset: 0fff370636c2b5ff1ac268b6d3d34cd95ba2068b 16:16:03 INFO - mozversion application_display_name: Nightly 16:16:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:16:03 INFO - mozversion application_name: Firefox 16:16:03 INFO - mozversion application_remotingname: firefox 16:16:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:16:03 INFO - mozversion application_vendor: Mozilla 16:16:03 INFO - mozversion application_version: 49.0a1 16:16:03 INFO - mozversion platform_buildid: 20160517142139 16:16:03 INFO - mozversion platform_changeset: 0fff370636c2b5ff1ac268b6d3d34cd95ba2068b 16:16:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:16:03 INFO - mozversion platform_version: 49.0a1 16:16:03 INFO - using testdate: 1463526963 16:16:03 INFO - actual date: 1463526963 16:16:03 INFO - starting webserver on 'localhost:49272' 16:16:03 INFO - SUITE-START | Running 2 tests 16:16:03 INFO - TEST-START | dromaeo_css 16:16:03 INFO - operating with platform_type : mac_ 16:16:03 INFO - Initialising browser for dromaeo_css test... 16:16:03 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpwzra9F/profile http://localhost:49272/getInfo.html) 16:16:06 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 16:16:06 INFO - PROCESS | 1665 | colorDepth:24 16:16:06 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 16:16:06 INFO - PROCESS | 1665 | __metrics 16:16:06 INFO - PROCESS | 1665 | 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" 16:16:06 ERROR - PROCESS | 1665 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:16:06 INFO - TEST-INFO | 1665: exit 0 16:16:06 INFO - Browser initialized. 16:16:06 INFO - Running cycle 1/1 for dromaeo_css test... 16:16:06 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpwzra9F/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:16:19 INFO - PROCESS | 1667 | RSS: Main: 238563328 16:16:19 INFO - PROCESS | 1667 | 16:17:10 INFO - PROCESS | 1667 | 1463527030762 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] 16:19:21 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 16:19:22 INFO - PROCESS | 1667 | RSS: Main: 357257216 16:19:22 INFO - PROCESS | 1667 | 16:22:25 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 16:22:26 INFO - PROCESS | 1667 | RSS: Main: 371814400 16:22:26 INFO - PROCESS | 1667 | 16:25:28 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 16:25:29 INFO - PROCESS | 1667 | RSS: Main: 369950720 16:25:29 INFO - PROCESS | 1667 | 16:28:31 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 16:28:32 INFO - PROCESS | 1667 | RSS: Main: 366866432 16:28:32 INFO - PROCESS | 1667 | 16:31:35 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 16:31:36 INFO - PROCESS | 1667 | RSS: Main: 334942208 16:31:36 INFO - PROCESS | 1667 | 16:34:38 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 16:34:39 INFO - PROCESS | 1667 | RSS: Main: 413720576 16:34:39 INFO - PROCESS | 1667 | 16:34:39 INFO - PROCESS | 1667 | __start_tp_report 16:34:39 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 16:34:39 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 16:34:39 INFO - PROCESS | 1667 | |i|pagename|runs| 16:34:39 INFO - PROCESS | 1667 | |0;dojo.html;3283.2284413162906;3445.242046749716;3471.4445688689816;3477.5479187705587;3529.5411596492454;4781.665283430152;5648.971755141229;5670.6030577859565;5672.120821272704;5769.740361683718;5758.7408566614495;5769.769209231626;5769.884602307962;5865.35481097079;5956.195913551669;5090.974545127269;5146.5110814472755;5265.736713164346;5269.209618557228;5314.468553144674;5258.947410525903;5281.313429254192;5291.973540132294;5310.468953104678;5328.680279183242;5104.183330667094;5143.305653736773;5145.716985565792;5181.663191892515;5236.162214045735;5131.640785145041;5159.355080614923;5164.509371609691;5164.845054648366;5191.714455704935;5174.508421699932;5195.896082078375;5197.662151960149;5229.137192363255;5260.132078207092;5577.386487486373;5626.831195064155;5689.715514224314;5757.740901659436;5797.623154494944;6188.009918413078;6220.03589443637;6242.843928901769;6259.248890133168;6269.279032911228;5557.194206840026;6262.686865656745;6272.6236425814595;6290.748370065192;6322.178116844781;5739.053056245756;5850.3564607893095;5909.438603332763;5918.053111502139;5950.761969521214;5856.238688970407;6315.084312774714;6315.368463153648;6327.145835312266;6334.809955701337;5742.339630942495;6164.3835616438;6220.906686399708;6227.750889964442;6244.656543890039;6164.845878852975;6177.752889884354;6205.100260462208;6231.40801623845;6261.154744109578;6134.038624681888;6145.340757995316;6186.783462578878;6204.317525072239;6235.781747638811;6238.65687387189;6246.843828904313;6253.187085678833;6258.968705156536;6271.05934109878;4897.853064408074;4963.982383611351;4966.428860680996;4970.378702662168;5001.674891132065;4401.779911004437;4419.9337009944875;4432.84485043022;4494.213512635276;4512.300593408027;5861.062230043258;5887.8233652990475;5889.970550147222;5912.615679980788;5913.349531551526;6147.678249176359;6256.43692067707;6261.373862613793;6265.968670156712;6271.686415679198;5698.572607054488;5810.1865738796605;5839.591228613956;5840.3575606683235;5863.794767183128;5396.082665946798;5398.2982212312145;5402.37872644643;5405.324334458192;5410.350757908997;6269.5611307208055;6369.617822930639;6376.840578985562;6377.266614339317;6379.521535884827;6243.063540468966;6289.528285378707;6301.40136687014;6303.810885673437;6308.684565771785;6357.841053973688;6370.55406121567;6379.776707815204;6382.6170429774365;6391.616503009835;6099.451049405575;6148.631082135086;6275.843103922439;6281.371862813682;6292.905606415816;6236.968815155895;6297.842553936188;6323.936760632335;6331.303556608677;6336.7465301389275;5580.855924535379;5945.3757355477055;6114.266288045463;6138.263408391022;6149.385061493994;4958.603311734981;5976.312724036791;6075.908861366995;6101.176341193882;6104.8836088941825;6027.75888964454;6044.939550604614;6056.6194754649405;6085.391460853879;6095.725692343766;6185.8144255672405;6194.876102478015;6211.565190436626;6212.565120441525;6219.315875253809;5341.359036915595;5974.820755377345;6004.249468816398;6042.214512113397;6079.908801367895 16:34:39 INFO - PROCESS | 1667 | |1;ext.html;502.3720349563046;508.87023808928717;544.5643485211831;552.4641098134811;559.7117484495485;31646.52530212046;34207.48688769667;44926.32610510845;44976.775116124416;47429.762851185784;46369.76815115928;46889;46921.296180557234;46944;46947.29579056317;8729.825403491926;9651.710448686516;9922.553485093153;9928.45393503357;9957.601695932153;8792;10542.420166890817;11898.940505297462;12119.939400302987;12131.454084566216;11996.280223186637;12068.336241506713;12072.396380181;12081.395930203498;12127;8838.320719063357;8997.685081022133;9011.729648110568;11907;11981.940090299537;8787.78030549235;10146.289759716823;11591.130665200144;11939.522419103225;12070.27578345302;39219.82340529789;39587.60412395897;42908.14183716339;43021.35467967983;43141;3291.1607540077366;3445.858257380252;3459.7232221422228;3466.3587236361354;3812.218495208476;30078.24804379886;30748.077557673307;30752.846235768677;31148.688513114805;31421.915151030218;2786.150224181624;2801.9719802801915;2809.2555472799722;2822.3508593023516;2872.669642991041;475835;476367.85448218306;477807;478736;479537;65931.67034164799;66209.66895165494;66515.66742166258;66684.33315666878;66920.6653966727;68631.31368686349;69889;69994.30005699878;70116;70117;17333;17606.647867042844;17609.823901760818;17637.470875873743;17646.823531764774;12361.381930903419;12556.686082847818;13267.270300133392;13413.86586134126;13449.731005379837;18604.906975465037;18631.627367452846;18638.72041919345;18716.812831871506;18767.718484222747;6836.931630683729;6889.379955804002;6950.513464057569;6997.160340759141;7019.684114214872;755.7694903054511;928.0997432490474;960.6566965901033;1110.5335758981248;1113.3208742666966;30184.925035120417;30395.392092158476;30911;31124.066278011695;31681.207969800478;1109.2346281066039;1112.8108221602347;1130.7399298161304;1135.8580177477816;1205.8492688413949;15566.844331556766;15719.607009824846;15750.369985200692;15952.680946381239;16045.358185672676;677843;684045;684427.3114537781;684769;686092;64642;64646.03030954539;64682.35317646763;64784;64928;1359.5173713331822;1481.148339704674;1507.773833924898;1528.2817075974274;1529.1818876900804;31605.84197079;31711.682883170874;32005.839970799996;32398.676013239565;32464.513032305007;55519.722401387735;56131.71934140303;56293.43706562882;56385.43614563802;56419.43580564142;541509;542877;543227;544684;544818.2759086179;27325.180244592695;28508;28524.42951141007;29270.70729292765;41911;47951.28073078837;48907;49488.752556238425;49926.251106234165;50066.74966625144;38677.80661096676;38718;40186.593469228435;40896.79551602223;41119.7944010278;37410.43884341683;38656.80671596624;39167;39339.409908851965;40211;38534.61465385422;39041;39445.408318874666;39820.40269396019;40022.39966400448;33828.985130446126;34623.65376346204;34920.12699682528;35094.64905351016;35128.648713512535;24505.50988980246;24828.053667191612;24999.87500062488;25659.23022309334;26132.34669133211 16:34:39 INFO - PROCESS | 1667 | |2;jquery.html;341.8154196733763;364.99635003649956;398.20955403524016;400.7199037476757;413.7744929013687;19387.806121938775;21697.891510542424;22055;22376.216832410868;22449.3265202044;14030.789538156936;21159.471013224676;21208.787912120875;21832.781672183275;22374;4903.632227582928;5294.311739473863;5477.178423236506;5477.726113694319;5485.0949593317055;3953.0710283083454;4153.875383738493;4156.438880751105;4172.207280616688;4174.060836311836;4168.8332466701295;4178.770167640779;5106.846794596168;5844.532437404998;5893.941060589382;4949.307097006386;5856.502197313233;5878.118282257653;5897.793577224798;5927.2887253529425;5118.129917913926;5229.869253268661;5459.836204913859;5477.123660214386;5494.450554944514;11361.068618334903;11818.054555635528;13745.793813092812;13877.722445551133;13894.374753136133;1605.486244401792;2051.446109550428;2089.9268525601533;2139.6362618354915;2219.4562332228707;9900.801983960353;10408.271421000532;12336.568220112253;14433.711325773531;15185.924070379688;1685.6628674265196;1695.6778212139777;1724.1810140183438;1757.7627020352218;1853.33280019193;132979;140446.89329660064;141619.99999999898;141662;142748.85876711857;11761.411929403495;11775.88224117765;11801.822972655416;11812;11814;15573.36479669641;15602.219889005504;15608.765868511982;15620.062796232263;15621.906466547232;8945.418547794372;9009.099090090938;9071.909280907106;9075.727728168165;9214.677486287948;8968;8973.820523589491;8975.50634715097;9108.453492790453;9110.407823491452;4737.8341758038305;4778.737169455644;4796.25658023007;4804.73573953429;4819.807207711722;1948.7661480622417;2006.8996550172433;2007.5382661987824;2013.7382140321665;2045.4784030072533;756.4893696754668;834.5660256666499;850.7575341027778;987.3138168972639;1020.3622735790132;20928.895355523124;21612.78387216108;21831;21933.451663708536;21974.01116949741;952.9761755956018;967.4147140979746;968.8982656821073;993.3344659078434;998.7053819123377;10942.288751231144;11261.042811360961;11499.942500287612;11505.597304094317;11554.480048397842;592768;600189;611553;613222;613960;103477;135015;138090;140664;141763.5823641771;1021.9080282774586;1040.193849766437;1070.8072546941626;1071.1163290285544;1079.8920107989138;18792.154353053873;19062;19233.61532769365;19241.230350786373;19246.133923973735;53056.73471632617;53371.4662853382;54795.17807232974;55377.7231113858;56210.71894640501;79809.20190798018;80662.5966870162;81302.18697812947;81895.77156342778;82191;6489.707963141765;6850.828729281808;8198.877016844634;8303.543305118277;8606.397552171287;7895.486793358414;9545.856812147684;12734.872651273368;12793.360331983178;12994.308302744541;9602.66390676323;9971.102600765966;10681.732956676145;10805.135589153006;11127.610533631285;9933.3543319684;10084.042016008467;10785.62250321235;11171.553137874394;11384;10982.615608453665;11427.971482566609;11638.882961036466;11797.587084452003;11917.463714133059;8233.83532329362;8308.667653294042;8387.706430274911;8517.48895066298;8544.102869198645;8334.583270836556;10457.529411176667;11226.270292430965;11574.28004418944;11605.535778568763 16:34:39 INFO - PROCESS | 1667 | |3;mootools.html;1442.632128807154;1624.7562865570162;1642.3923148435078;1840.953976150596;1958.3733205374285;8066.878996815052;8750.256228220602;8971.91028089719;8983.505907175102;9097.81804363914;8711.695090671828;8936.731898043068;8936.91063089369;8943.418677785956;8947.507887066226;2206.6910632511463;2281.121768119278;2283.577538155438;2467.629855521668;2507.1726330310953;2159.5896779611903;2287.3481057898503;2311.6648086027517;2464.2730394533655;2553.2850801775535;2471.159805666073;2495.987520062397;2503.962440563393;2509.3475696318997;2512.309114993373;2477.2320580620067;2487.664165337674;2495.126705653025;2499.4376265340334;2508.3603681061213;2178.70587470691;2407.65089062085;2479.962800557993;2482.6276058591357;2493.763092506209;8938.284937205008;8996.550172491348;9005.459672419676;9016;9032.954835225848;5385.165299378603;5561.916571251434;5564.165375193713;5575.466746644664;5586.190002449622;3251.7886337387995;3548.3258180945536;3605.1167463971233;3665.871694490707;3691.2248427830186;893.2898345815028;987.274353350287;1054.114543783218;1062.1608928946123;1067.4929408530963;12159.392030398445;12170.330631815246;12189.99999999991;12190.51237950472;12195.634130976085;4875.756212189376;4921.360223170965;5040.092783298968;5047.924281135786;5060.418051924003;10279.074883260542;10298.536565854554;10299;10306.845397319046;10315.84526232107;5499.642523235977;5572.4706152915405;5577.27495425592;5581;5587.916181257285;4540.160070386989;4897.902041959141;4991.251312303184;5076.340075790197;5117.257997590328;3482.495038219444;3869.941950870739;4130.91738165235;4213.726107802983;4225.302825033897;1167.2569461764786;1305.556110922272;1355.6949686320697;1392.8398234203196;1403.368484182122;819.8358331169753;824.583585289425;908.8227795579799;909.6997990663066;940.7130825098411;5652.123920792284;5846.619969701956;5930.28836539618;5972.074328479092;6050.515958723292;967.2165545907837;979.5983646704817;979.8383266761048;980.9509524523888;988.7033889833021;3889.086064774776;3953.723239373216;3958.4062390641634;4020.979895100506;4046.5751096135073;9556;10099.69700908974;10881.891181088246;10940.398278094623;11101.1674124441;10899.782004359868;10981.39602321864;11002.779944401067;11003.394813285186;11024.724381890357;8014.959925200454;8273.172682731678;8360.498370097814;8362.247397734232;8414.242718155396;6038.184845046038;6053.364396738279;6175.722092505938;6192;6218.81343559694;10158;10458.68623941283;10460.110890574082;10468.790624187626;10480.895191047992;10100.646477373257;10276;10653.627123050655;10662.8400573993;10691.144708423462;7185.389241914496;7441.888371674538;7838.686452541834;7975.800604984922;7996.520208787491;8293.295069918882;8581.65673373058;8585.356098292528;8698.13018698125;8714.869276960977;7506.211847756125;7599.772006839804;7685.154632990255;7733.149353570991;7843.647035883285;7470.028896243453;7472.962635186789;7478.551286922802;7575.015248017722;7652.120006199136;7128.28717128283;7203.279672032755;7214.927850721426;7225.602591857499;7232.0959880015;4769.928451073168;4848.733319667452;4962.602991760723;4974.32846565709;5154.407243167084;4933.802647894044;4998.075356059141;5031.798728050837;5112.97443512795;5276.129438642581 16:34:39 INFO - PROCESS | 1667 | |4;prototype.html;307.1231633685827;313.8713127617676;326.2138246825151;329.58966087221415;329.7708092875451;4456.331550267458;4676.532346765322;5325.08297013075;5354.14333706607;5428.4300148484335;3705.6850167735774;4407.537208593099;5042.881989564383;5095.312132862063;5283;3398.674516938396;3664.9450258246147;3745.981270093646;4026.3960405939124;4059.9797001014954;3898.894596917661;3917.334053210961;3992.6425015494724;4049.3521036634143;4070.8982275443054;3370.511275864998;3719.9442008369897;3826.9043273918096;3883.893090469203;3923.5880232575596;3339.782914110575;3404.540387047766;3942.2062961352517;3987.820548075329;4014;3497.9125521862156;3534.469829525566;3540.7521473496868;3543.255916257589;3545.2377738786026;4032.3951407288846;4647.465541462741;5051.6716413433005;5119.846404607868;5238.476152384769;4096.50113430807;4267.893302667427;4571;4589.586937175637;4659.277811939155;4475.328700694889;4912.852614421573;5542.418046105182;5571.944280557183;5583.609147359686;3533.922153743107;3726.9937116978263;3758.605346438612;3825.9617403825882;3962.346212874872;5670.5463562914865;5727;5748.5688573357165;5759.942400575983;5853.619514731571;3389.3560223557447;3572.1069732566857;3862.092408284051;3955.248502784462;3996.580359062257;3603.6756691897854;3625.169289508834;3975.980120099381;3984.0438294809037;4093.1813637272667;3348.5981682198294;3742.9438558421643;4167.666346769065;4539.319102134707;4560.498345182027;4500.752458614742;4876.780544875489;4933.186024305949;4997.175466048134;5071.670341427869;1127.971800704989;1349.9402968669547;1351.1892864281353;1351.9256440895845;1355.1801160297878;857.8804659918866;858.52780970466;860.4536119564121;872.1815015692353;911.6125646600168;4382.452193475816;5142.331496905378;5188.365664815571;5203.2195170723935;5228.2680424740565;4697.084068606658;5629.690367029771;5654.886902261932;5717.742701578356;5765.250517432707;4000.3599424092004;4179.874603761831;4550.930531325136;5068.873278168076;5219.269302297681;2981.0013645429026;2990.566367876646;3017.743491803177;3030.075826872825;3086.1791542989745;5287.123071309629;5382.712347252402;5439.836804895859;5955.40445955401;5965.492933100735;5338.199270109437;5619.342294023273;5908.350081491032;5922.7927022554;5924.170616113747;5072.985402919431;5895.322037965603;5901.2033375495475;5930.644161350333;6006.399360064046;5246.6065045121;5464.781408743605;5647.491725744702;5678.346990096192;5785.991284532104;4994.9500504994485;5628.915566266427;5685.971570142288;5740.569457290637;5860.241927421842;5064.772085256246;5906.557008224315;6078.878422431439;6079.817605471843;6131.570790044654;3800;3926.3325234710737;3929.135590170158;3945.723799334019;3949.9407508887966;5760.020796464695;5808.390119037609;5829.417058294137;5875.559333049953;5882.999890018621;4396.944733263929;4972.65191436596;4997.75056235941;5727.455891690282;5851.414858514115;4612.146752850734;5192.766325515286;5383.7308134593895;5549.667019978814;5602.467765562265;4768.354917553577;4828.5412885777205;5175.499105259432;5416.995711586738;5448.400675925565;4717.938463845745;4944.728039957837;5157.845264641916;5335.253064570886;5586.748596313095;4725.50382209863;4764.857054288377;5377.542908852635;5498.718032016668;5564.165375193753 16:34:39 INFO - PROCESS | 1667 | |5;yui.html;643.7553729582758;665.7536711416775;686.241702918275;709.6522703875099;734.8934404511346;12949.35253237338;14880.032797868145;15597.376104955789;15604.297806598703;15650.217489125554;13237.205767653948;14364.20996845173;14411.855881441183;14442.350094245758;14507.854921450782;910.4036855859406;913.4108500017485;925.296774451415;926.7451450851003;927.601131513449;919.4850883505225;923.3075193604797;923.5105394141083;924.8242833861544;926.6525053105086;962.5379817687514;963.7831487915198;964.753987733127;965.1988849255101;965.946872921989;960.8606752020987;960.9375390599589;961.711486554031;964.6382606522554;964.9915837949325;716.1763971432841;717.2683862460261;718.3247747117692;719.863225987066;722.3607107709714;13179.275139867303;13230.338483075808;13265.137766045176;13268.137571057947;13273.734525309535;11098.61154859576;11109.777804443947;11284.097272218203;11323.943380283128;11590.072794176445;9101.180893719531;9354.251659867263;9651.179649729782;10083.394996300172;10093.293469457141;3954.3787047310752;4035.233305671913;4204.348326009474;4289.313709806447;4306;53961.46038539643;67921;69994;83652;85163.57418212932;7625.542467451971;9400.294977876687;9446.38598491096;9463.905360946303;9659;20980.685289720666;21538.461538461663;21576;21599.244026458997;21640.675389869164;7411.6664750086375;7493.3630641395;7515.624218789038;7538.170801211862;7620.885686714703;3690.7970061646865;3919.8824035278985;3999.1801680655394;4010.3583426651594;4055;3359.7480188985437;3500.492428597839;3586.946195807065;3679.7056235501095;3680.5399325084363;640.1486023588616;647.1134545672459;658.9538732288789;660.6696651674163;670.735059651435;535.5019831556612;576.7375843990974;607.7629724407485;619.1146660275849;625.1373105114913;7926.207379262028;7941.483803552866;8129.024517057991;8171.713990010321;8285.171482851667;575.3096284458583;591.6420565557776;614.5145335185243;619.2012304127715;630.3538872655565;3514.4201206801104;3537.1510837399355;3837.347650899298;3900.7074469414906;3992.7803970781306;88651;90965.54517227372;92133.53933230425;92990.53504732433;94129.5293523528;81526.59236703778;82414;83474.9131271711;84188.57905710554;85249.57375213085;62867.6856615714;69361.26596835018;69969;70284.24289392807;74828;8098.7165449209;8129.552874591955;8263.462874913112;8313.293370063613;8314.877491538566;41428.34286628501;45193.54806451893;46207.53792462167;46303.74142569286;46979.06041879075;62162.68918655378;62424;63857.68071159614;65051.67474162599;65101;10902.56389744433;11025.724356891142;11089.22375433712;11375.43122843838;11401.543938242377;10042.69871903844;12343.876561234632;12395.690107747378;13041.217526948414;13073.15024523403;11434.08527317796;11536.711582210177;11637.301761893974;11705.648830535098;11928.582499612472;11448.94275528617;11659.591914282959;11922.880771192524;11925.522979081086;11927.224730392496;8706.738797836075;11019.724506887393;11083.944580277048;11272.267302625303;11620.186586939171;8500.53247071417;9567.01932585602;9591.760205994906;9714.070431471946;9743.853842192231;7744.5740484271655;8836.823263534565;8867.467951922905;9367.531623418938;9385.953070234606 16:34:39 INFO - PROCESS | 1667 | __end_tp_report 16:34:39 INFO - PROCESS | 1667 | __start_cc_report 16:34:39 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1188 16:34:39 INFO - PROCESS | 1667 | __end_cc_report 16:34:39 INFO - PROCESS | 1667 | __startTimestamp1463528079252__endTimestamp 16:34:39 INFO - PROCESS | 1667 | 16:34:39 INFO - PROCESS | 1667 | ------- Summary: start ------- 16:34:39 INFO - PROCESS | 1667 | Number of tests: 6 16:34:39 INFO - PROCESS | 1667 | 16:34:39 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5758.89 Median:5974.82 stddev:644.34 (10.8%) stddev-sans-first:616.35 16:34:39 INFO - PROCESS | 1667 | Values: 3283.2 3445.2 3471.4 3477.5 3529.5 4781.7 5649.0 5670.6 5672.1 5769.7 5758.7 5769.8 5769.9 5865.4 5956.2 5091.0 5146.5 5265.7 5269.2 5314.5 5258.9 5281.3 5292.0 5310.5 5328.7 5104.2 5143.3 5145.7 5181.7 5236.2 5131.6 5159.4 5164.5 5164.8 5191.7 5174.5 5195.9 5197.7 5229.1 5260.1 5577.4 5626.8 5689.7 5757.7 5797.6 6188.0 6220.0 6242.8 6259.2 6269.3 5557.2 6262.7 6272.6 6290.7 6322.2 5739.1 5850.4 5909.4 5918.1 5950.8 5856.2 6315.1 6315.4 6327.1 6334.8 5742.3 6164.4 6220.9 6227.8 6244.7 6164.8 6177.8 6205.1 6231.4 6261.2 6134.0 6145.3 6186.8 6204.3 6235.8 6238.7 6246.8 6253.2 6259.0 6271.1 4897.9 4964.0 4966.4 4970.4 5001.7 4401.8 4419.9 4432.8 4494.2 4512.3 5861.1 5887.8 5890.0 5912.6 5913.3 6147.7 6256.4 6261.4 6266.0 6271.7 5698.6 5810.2 5839.6 5840.4 5863.8 5396.1 5398.3 5402.4 5405.3 5410.4 6269.6 6369.6 6376.8 6377.3 6379.5 6243.1 6289.5 6301.4 6303.8 6308.7 6357.8 6370.6 6379.8 6382.6 6391.6 6099.5 6148.6 6275.8 6281.4 6292.9 6237.0 6297.8 6323.9 6331.3 6336.7 5580.9 5945.4 6114.3 6138.3 6149.4 4958.6 5976.3 6075.9 6101.2 6104.9 6027.8 6044.9 6056.6 6085.4 6095.7 6185.8 6194.9 6211.6 6212.6 6219.3 5341.4 5974.8 6004.2 6042.2 6079.9 16:34:39 INFO - PROCESS | 1667 | 16:34:39 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:71645.00 Median:38667.31 stddev:153416.63 (396.8%) stddev-sans-first:153754.02 16:34:39 INFO - PROCESS | 1667 | Values: 502.4 508.9 544.6 552.5 559.7 31646.5 34207.5 44926.3 44976.8 47429.8 46369.8 46889.0 46921.3 46944.0 46947.3 8729.8 9651.7 9922.6 9928.5 9957.6 8792.0 10542.4 11898.9 12119.9 12131.5 11996.3 12068.3 12072.4 12081.4 12127.0 8838.3 8997.7 9011.7 11907.0 11981.9 8787.8 10146.3 11591.1 11939.5 12070.3 39219.8 39587.6 42908.1 43021.4 43141.0 3291.2 3445.9 3459.7 3466.4 3812.2 30078.2 30748.1 30752.8 31148.7 31421.9 2786.2 2802.0 2809.3 2822.4 2872.7 475835.0 476367.9 477807.0 478736.0 479537.0 65931.7 66209.7 66515.7 66684.3 66920.7 68631.3 69889.0 69994.3 70116.0 70117.0 17333.0 17606.6 17609.8 17637.5 17646.8 12361.4 12556.7 13267.3 13413.9 13449.7 18604.9 18631.6 18638.7 18716.8 18767.7 6836.9 6889.4 6950.5 6997.2 7019.7 755.8 928.1 960.7 1110.5 1113.3 30184.9 30395.4 30911.0 31124.1 31681.2 1109.2 1112.8 1130.7 1135.9 1205.8 15566.8 15719.6 15750.4 15952.7 16045.4 677843.0 684045.0 684427.3 684769.0 686092.0 64642.0 64646.0 64682.4 64784.0 64928.0 1359.5 1481.1 1507.8 1528.3 1529.2 31605.8 31711.7 32005.8 32398.7 32464.5 55519.7 56131.7 56293.4 56385.4 56419.4 541509.0 542877.0 543227.0 544684.0 544818.3 27325.2 28508.0 28524.4 29270.7 41911.0 47951.3 48907.0 49488.8 49926.3 50066.7 38677.8 38718.0 40186.6 40896.8 41119.8 37410.4 38656.8 39167.0 39339.4 40211.0 38534.6 39041.0 39445.4 39820.4 40022.4 33829.0 34623.7 34920.1 35094.6 35128.6 24505.5 24828.1 24999.9 25659.2 26132.3 16:34:39 INFO - PROCESS | 1667 | 16:34:39 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:35791.05 Median:12296.73 stddev:101929.79 (828.9%) stddev-sans-first:102180.98 16:34:39 INFO - PROCESS | 1667 | Values: 341.8 365.0 398.2 400.7 413.8 19387.8 21697.9 22055.0 22376.2 22449.3 14030.8 21159.5 21208.8 21832.8 22374.0 4903.6 5294.3 5477.2 5477.7 5485.1 3953.1 4153.9 4156.4 4172.2 4174.1 4168.8 4178.8 5106.8 5844.5 5893.9 4949.3 5856.5 5878.1 5897.8 5927.3 5118.1 5229.9 5459.8 5477.1 5494.5 11361.1 11818.1 13745.8 13877.7 13894.4 1605.5 2051.4 2089.9 2139.6 2219.5 9900.8 10408.3 12336.6 14433.7 15185.9 1685.7 1695.7 1724.2 1757.8 1853.3 132979.0 140446.9 141620.0 141662.0 142748.9 11761.4 11775.9 11801.8 11812.0 11814.0 15573.4 15602.2 15608.8 15620.1 15621.9 8945.4 9009.1 9071.9 9075.7 9214.7 8968.0 8973.8 8975.5 9108.5 9110.4 4737.8 4778.7 4796.3 4804.7 4819.8 1948.8 2006.9 2007.5 2013.7 2045.5 756.5 834.6 850.8 987.3 1020.4 20928.9 21612.8 21831.0 21933.5 21974.0 953.0 967.4 968.9 993.3 998.7 10942.3 11261.0 11499.9 11505.6 11554.5 592768.0 600189.0 611553.0 613222.0 613960.0 103477.0 135015.0 138090.0 140664.0 141763.6 1021.9 1040.2 1070.8 1071.1 1079.9 18792.2 19062.0 19233.6 19241.2 19246.1 53056.7 53371.5 54795.2 55377.7 56210.7 79809.2 80662.6 81302.2 81895.8 82191.0 6489.7 6850.8 8198.9 8303.5 8606.4 7895.5 9545.9 12734.9 12793.4 12994.3 9602.7 9971.1 10681.7 10805.1 11127.6 9933.4 10084.0 10785.6 11171.6 11384.0 10982.6 11428.0 11638.9 11797.6 11917.5 8233.8 8308.7 8387.7 8517.5 8544.1 8334.6 10457.5 11226.3 11574.3 11605.5 16:34:39 INFO - PROCESS | 1667 | 16:34:39 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5862.39 Median:4982.79 stddev:3290.06 (66.0%) stddev-sans-first:3282.53 16:34:39 INFO - PROCESS | 1667 | Values: 1442.6 1624.8 1642.4 1841.0 1958.4 8066.9 8750.3 8971.9 8983.5 9097.8 8711.7 8936.7 8936.9 8943.4 8947.5 2206.7 2281.1 2283.6 2467.6 2507.2 2159.6 2287.3 2311.7 2464.3 2553.3 2471.2 2496.0 2504.0 2509.3 2512.3 2477.2 2487.7 2495.1 2499.4 2508.4 2178.7 2407.7 2480.0 2482.6 2493.8 8938.3 8996.6 9005.5 9016.0 9033.0 5385.2 5561.9 5564.2 5575.5 5586.2 3251.8 3548.3 3605.1 3665.9 3691.2 893.3 987.3 1054.1 1062.2 1067.5 12159.4 12170.3 12190.0 12190.5 12195.6 4875.8 4921.4 5040.1 5047.9 5060.4 10279.1 10298.5 10299.0 10306.8 10315.8 5499.6 5572.5 5577.3 5581.0 5587.9 4540.2 4897.9 4991.3 5076.3 5117.3 3482.5 3869.9 4130.9 4213.7 4225.3 1167.3 1305.6 1355.7 1392.8 1403.4 819.8 824.6 908.8 909.7 940.7 5652.1 5846.6 5930.3 5972.1 6050.5 967.2 979.6 979.8 981.0 988.7 3889.1 3953.7 3958.4 4021.0 4046.6 9556.0 10099.7 10881.9 10940.4 11101.2 10899.8 10981.4 11002.8 11003.4 11024.7 8015.0 8273.2 8360.5 8362.2 8414.2 6038.2 6053.4 6175.7 6192.0 6218.8 10158.0 10458.7 10460.1 10468.8 10480.9 10100.6 10276.0 10653.6 10662.8 10691.1 7185.4 7441.9 7838.7 7975.8 7996.5 8293.3 8581.7 8585.4 8698.1 8714.9 7506.2 7599.8 7685.2 7733.1 7843.6 7470.0 7473.0 7478.6 7575.0 7652.1 7128.3 7203.3 7214.9 7225.6 7232.1 4769.9 4848.7 4962.6 4974.3 5154.4 4933.8 4998.1 5031.8 5113.0 5276.1 16:34:39 INFO - PROCESS | 1667 | 16:34:39 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:4430.48 Median:4894.82 stddev:1374.17 (28.1%) stddev-sans-first:1342.72 16:34:39 INFO - PROCESS | 1667 | Values: 307.1 313.9 326.2 329.6 329.8 4456.3 4676.5 5325.1 5354.1 5428.4 3705.7 4407.5 5042.9 5095.3 5283.0 3398.7 3664.9 3746.0 4026.4 4060.0 3898.9 3917.3 3992.6 4049.4 4070.9 3370.5 3719.9 3826.9 3883.9 3923.6 3339.8 3404.5 3942.2 3987.8 4014.0 3497.9 3534.5 3540.8 3543.3 3545.2 4032.4 4647.5 5051.7 5119.8 5238.5 4096.5 4267.9 4571.0 4589.6 4659.3 4475.3 4912.9 5542.4 5571.9 5583.6 3533.9 3727.0 3758.6 3826.0 3962.3 5670.5 5727.0 5748.6 5759.9 5853.6 3389.4 3572.1 3862.1 3955.2 3996.6 3603.7 3625.2 3976.0 3984.0 4093.2 3348.6 3742.9 4167.7 4539.3 4560.5 4500.8 4876.8 4933.2 4997.2 5071.7 1128.0 1349.9 1351.2 1351.9 1355.2 857.9 858.5 860.5 872.2 911.6 4382.5 5142.3 5188.4 5203.2 5228.3 4697.1 5629.7 5654.9 5717.7 5765.3 4000.4 4179.9 4550.9 5068.9 5219.3 2981.0 2990.6 3017.7 3030.1 3086.2 5287.1 5382.7 5439.8 5955.4 5965.5 5338.2 5619.3 5908.4 5922.8 5924.2 5073.0 5895.3 5901.2 5930.6 6006.4 5246.6 5464.8 5647.5 5678.3 5786.0 4995.0 5628.9 5686.0 5740.6 5860.2 5064.8 5906.6 6078.9 6079.8 6131.6 3800.0 3926.3 3929.1 3945.7 3949.9 5760.0 5808.4 5829.4 5875.6 5883.0 4396.9 4972.7 4997.8 5727.5 5851.4 4612.1 5192.8 5383.7 5549.7 5602.5 4768.4 4828.5 5175.5 5417.0 5448.4 4717.9 4944.7 5157.8 5335.3 5586.7 4725.5 4764.9 5377.5 5498.7 5564.2 16:34:39 INFO - PROCESS | 1667 | 16:34:39 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:17822.93 Median:32248.91 stddev:25170.95 (78.1%) stddev-sans-first:25208.51 16:34:39 INFO - PROCESS | 1667 | Values: 643.8 665.8 686.2 709.7 734.9 12949.4 14880.0 15597.4 15604.3 15650.2 13237.2 14364.2 14411.9 14442.4 14507.9 910.4 913.4 925.3 926.7 927.6 919.5 923.3 923.5 924.8 926.7 962.5 963.8 964.8 965.2 965.9 960.9 960.9 961.7 964.6 965.0 716.2 717.3 718.3 719.9 722.4 13179.3 13230.3 13265.1 13268.1 13273.7 11098.6 11109.8 11284.1 11323.9 11590.1 9101.2 9354.3 9651.2 10083.4 10093.3 3954.4 4035.2 4204.3 4289.3 4306.0 53961.5 67921.0 69994.0 83652.0 85163.6 7625.5 9400.3 9446.4 9463.9 9659.0 20980.7 21538.5 21576.0 21599.2 21640.7 7411.7 7493.4 7515.6 7538.2 7620.9 3690.8 3919.9 3999.2 4010.4 4055.0 3359.7 3500.5 3586.9 3679.7 3680.5 640.1 647.1 659.0 660.7 670.7 535.5 576.7 607.8 619.1 625.1 7926.2 7941.5 8129.0 8171.7 8285.2 575.3 591.6 614.5 619.2 630.4 3514.4 3537.2 3837.3 3900.7 3992.8 88651.0 90965.5 92133.5 92990.5 94129.5 81526.6 82414.0 83474.9 84188.6 85249.6 62867.7 69361.3 69969.0 70284.2 74828.0 8098.7 8129.6 8263.5 8313.3 8314.9 41428.3 45193.5 46207.5 46303.7 46979.1 62162.7 62424.0 63857.7 65051.7 65101.0 10902.6 11025.7 11089.2 11375.4 11401.5 10042.7 12343.9 12395.7 13041.2 13073.2 11434.1 11536.7 11637.3 11705.6 11928.6 11448.9 11659.6 11922.9 11925.5 11927.2 8706.7 11019.7 11083.9 11272.3 11620.2 8500.5 9567.0 9591.8 9714.1 9743.9 7744.6 8836.8 8867.5 9367.5 9386.0 16:34:39 INFO - PROCESS | 1667 | -------- Summary: end -------- 16:34:39 INFO - PROCESS | 1667 | 16:34:39 INFO - TEST-INFO | 1667: exit 0 16:34:39 INFO - TEST-OK | dromaeo_css | took 1116005ms 16:34:39 INFO - TEST-START | kraken 16:34:39 INFO - operating with platform_type : mac_ 16:34:39 INFO - Initialising browser for kraken test... 16:34:39 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpShLZtM/profile http://localhost:49272/getInfo.html) 16:34:42 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 16:34:42 INFO - PROCESS | 1675 | colorDepth:24 16:34:42 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 16:34:42 INFO - PROCESS | 1675 | __metrics 16:34:42 INFO - PROCESS | 1675 | 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" 16:34:42 ERROR - PROCESS | 1675 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:34:42 INFO - TEST-INFO | 1675: exit 0 16:34:42 INFO - Browser initialized. 16:34:42 INFO - Running cycle 1/1 for kraken test... 16:34:42 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpShLZtM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:34:54 INFO - PROCESS | 1677 | RSS: Main: 238710784 16:34:54 INFO - PROCESS | 1677 | 16:34:57 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 16:34:58 INFO - PROCESS | 1677 | RSS: Main: 286425088 16:34:58 INFO - PROCESS | 1677 | 16:35:01 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 16:35:01 INFO - PROCESS | 1677 | RSS: Main: 295591936 16:35:01 INFO - PROCESS | 1677 | 16:35:05 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 16:35:06 INFO - PROCESS | 1677 | RSS: Main: 386248704 16:35:06 INFO - PROCESS | 1677 | 16:35:08 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 16:35:08 INFO - PROCESS | 1677 | RSS: Main: 284868608 16:35:08 INFO - PROCESS | 1677 | 16:35:11 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:35:11 INFO - PROCESS | 1677 | RSS: Main: 288845824 16:35:11 INFO - PROCESS | 1677 | 16:35:14 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 16:35:15 INFO - PROCESS | 1677 | RSS: Main: 328089600 16:35:15 INFO - PROCESS | 1677 | 16:35:18 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 16:35:19 INFO - PROCESS | 1677 | RSS: Main: 331792384 16:35:19 INFO - PROCESS | 1677 | 16:35:22 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 16:35:22 INFO - PROCESS | 1677 | RSS: Main: 327565312 16:35:22 INFO - PROCESS | 1677 | 16:35:24 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:35:25 INFO - PROCESS | 1677 | RSS: Main: 315637760 16:35:25 INFO - PROCESS | 1677 | 16:35:27 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:35:27 INFO - PROCESS | 1677 | RSS: Main: 322875392 16:35:27 INFO - PROCESS | 1677 | 16:35:30 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:35:31 INFO - PROCESS | 1677 | RSS: Main: 324612096 16:35:31 INFO - PROCESS | 1677 | 16:35:34 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:35:34 INFO - PROCESS | 1677 | RSS: Main: 329101312 16:35:34 INFO - PROCESS | 1677 | 16:35:37 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:35:38 INFO - PROCESS | 1677 | RSS: Main: 326664192 16:35:38 INFO - PROCESS | 1677 | 16:35:39 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 16:35:40 INFO - PROCESS | 1677 | RSS: Main: 373833728 16:35:40 INFO - PROCESS | 1677 | 16:35:40 INFO - PROCESS | 1677 | __start_tp_report 16:35:40 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 16:35:40 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 16:35:40 INFO - PROCESS | 1677 | |i|pagename|runs| 16:35:40 INFO - PROCESS | 1677 | |0;ai-astar;112;107;113;112;111;111;111;111;120;163 16:35:40 INFO - PROCESS | 1677 | |1;audio-beat-detection;152;117;141;193;143;138;117;149;155;118 16:35:40 INFO - PROCESS | 1677 | |2;audio-dft;182;185;184;184;183;184;187;184;179;184 16:35:40 INFO - PROCESS | 1677 | |3;audio-fft;78;72;57;155;80;85;55;58;113;56 16:35:40 INFO - PROCESS | 1677 | |4;audio-oscillator;91;95;133;78;79;77;130;113;113;102 16:35:40 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;88;87;90;85;90;85;88;86;88;85 16:35:40 INFO - PROCESS | 1677 | |6;imaging-darkroom;96;94;96;112;117;94;99;147;95;99 16:35:40 INFO - PROCESS | 1677 | |7;imaging-desaturate;125;132;84;84;86;83;84;86;86;84 16:35:40 INFO - PROCESS | 1677 | |8;json-parse-financial;55;56;57;56;58;55;55;120;57;56 16:35:40 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;64;67;82;100;100;100;69;66;68;67 16:35:40 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;84;85;83;97;106;105;106;85;84;85 16:35:40 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;135;147;141;144;142;143;146;133;217;153 16:35:40 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;163;165;164;163;162;162;164;163;162;180 16:35:40 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;66;65;66;65;65;64;65;64;64;65 16:35:40 INFO - PROCESS | 1677 | __end_tp_report 16:35:40 INFO - PROCESS | 1677 | __start_cc_report 16:35:40 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,2312 16:35:40 INFO - PROCESS | 1677 | __end_cc_report 16:35:40 INFO - PROCESS | 1677 | __startTimestamp1463528140211__endTimestamp 16:35:40 INFO - PROCESS | 1677 | 16:35:40 INFO - PROCESS | 1677 | ------- Summary: start ------- 16:35:40 INFO - PROCESS | 1677 | Number of tests: 14 16:35:40 INFO - PROCESS | 1677 | 16:35:40 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:117.10 Median:112.00 stddev:16.45 (14.7%) stddev-sans-first:17.34 16:35:40 INFO - PROCESS | 1677 | Values: 112.0 107.0 113.0 112.0 111.0 111.0 111.0 111.0 120.0 163.0 16:35:40 INFO - PROCESS | 1677 | 16:35:40 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:142.30 Median:146.00 stddev:23.00 (15.8%) stddev-sans-first:24.13 16:35:40 INFO - PROCESS | 1677 | Values: 152.0 117.0 141.0 193.0 143.0 138.0 117.0 149.0 155.0 118.0 16:35:40 INFO - PROCESS | 1677 | 16:35:40 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:2.07 (1.1%) stddev-sans-first:2.11 16:35:40 INFO - PROCESS | 1677 | Values: 182.0 185.0 184.0 184.0 183.0 184.0 187.0 184.0 179.0 184.0 16:35:40 INFO - PROCESS | 1677 | 16:35:40 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:80.90 Median:65.00 stddev:31.65 (48.7%) stddev-sans-first:33.55 16:35:40 INFO - PROCESS | 1677 | Values: 78.0 72.0 57.0 155.0 80.0 85.0 55.0 58.0 113.0 56.0 16:35:40 INFO - PROCESS | 1677 | 16:35:40 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:101.10 Median:77.50 stddev:20.76 (26.8%) stddev-sans-first:21.70 16:35:40 INFO - PROCESS | 1677 | Values: 91.0 95.0 133.0 78.0 79.0 77.0 130.0 113.0 113.0 102.0 16:35:40 INFO - PROCESS | 1677 | 16:35:40 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:87.20 Median:88.00 stddev:1.93 (2.2%) stddev-sans-first:2.03 16:35:40 INFO - PROCESS | 1677 | Values: 88.0 87.0 90.0 85.0 90.0 85.0 88.0 86.0 88.0 85.0 16:35:40 INFO - PROCESS | 1677 | 16:35:40 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:104.90 Median:95.50 stddev:16.78 (17.6%) stddev-sans-first:17.48 16:35:40 INFO - PROCESS | 1677 | Values: 96.0 94.0 96.0 112.0 117.0 94.0 99.0 147.0 95.0 99.0 16:35:40 INFO - PROCESS | 1677 | 16:35:40 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:93.40 Median:84.00 stddev:18.60 (22.1%) stddev-sans-first:15.83 16:35:40 INFO - PROCESS | 1677 | Values: 125.0 132.0 84.0 84.0 86.0 83.0 84.0 86.0 86.0 84.0 16:35:40 INFO - PROCESS | 1677 | 16:35:40 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:56.00 stddev:20.23 (36.1%) stddev-sans-first:21.27 16:35:40 INFO - PROCESS | 1677 | Values: 55.0 56.0 57.0 56.0 58.0 55.0 55.0 120.0 57.0 56.0 16:35:40 INFO - PROCESS | 1677 | 16:35:40 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:78.30 Median:67.00 stddev:15.74 (23.5%) stddev-sans-first:15.82 16:35:40 INFO - PROCESS | 1677 | Values: 64.0 67.0 82.0 100.0 100.0 100.0 69.0 66.0 68.0 67.0 16:35:40 INFO - PROCESS | 1677 | 16:35:40 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:92.00 Median:84.50 stddev:10.23 (12.1%) stddev-sans-first:10.43 16:35:40 INFO - PROCESS | 1677 | Values: 84.0 85.0 83.0 97.0 106.0 105.0 106.0 85.0 84.0 85.0 16:35:40 INFO - PROCESS | 1677 | 16:35:40 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:150.10 Median:145.00 stddev:24.19 (16.7%) stddev-sans-first:25.03 16:35:40 INFO - PROCESS | 1677 | Values: 135.0 147.0 141.0 144.0 142.0 143.0 146.0 133.0 217.0 153.0 16:35:40 INFO - PROCESS | 1677 | 16:35:40 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.80 Median:163.50 stddev:5.43 (3.3%) stddev-sans-first:5.72 16:35:40 INFO - PROCESS | 1677 | Values: 163.0 165.0 164.0 163.0 162.0 162.0 164.0 163.0 162.0 180.0 16:35:40 INFO - PROCESS | 1677 | 16:35:40 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.90 Median:65.00 stddev:0.74 (1.1%) stddev-sans-first:0.67 16:35:40 INFO - PROCESS | 1677 | Values: 66.0 65.0 66.0 65.0 65.0 64.0 65.0 64.0 64.0 65.0 16:35:40 INFO - PROCESS | 1677 | -------- Summary: end -------- 16:35:40 INFO - PROCESS | 1677 | 16:35:41 INFO - TEST-INFO | 1677: exit 0 16:35:41 INFO - TEST-OK | kraken | took 61182ms 16:35:41 INFO - SUITE-END | took 1177s 16:35:41 INFO - Completed test suite (00:19:37) 16:35:41 INFO - javascript benchmark 16:35:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3283.2284413162906, 3445.242046749716, 3471.4445688689816, 3477.5479187705587, 3529.5411596492454, 4781.665283430152, 5648.971755141229, 5670.6030577859565, 5672.120821272704, 5769.740361683718, 5758.7408566614495, 5769.769209231626, 5769.884602307962, 5865.35481097079, 5956.195913551669, 5090.974545127269, 5146.5110814472755, 5265.736713164346, 5269.209618557228, 5314.468553144674, 5258.947410525903, 5281.313429254192, 5291.973540132294, 5310.468953104678, 5328.680279183242, 5104.183330667094, 5143.305653736773, 5145.716985565792, 5181.663191892515, 5236.162214045735, 5131.640785145041, 5159.355080614923, 5164.509371609691, 5164.845054648366, 5191.714455704935, 5174.508421699932, 5195.896082078375, 5197.662151960149, 5229.137192363255, 5260.132078207092, 5577.386487486373, 5626.831195064155, 5689.715514224314, 5757.740901659436, 5797.623154494944, 6188.009918413078, 6220.03589443637, 6242.843928901769, 6259.248890133168, 6269.279032911228, 5557.194206840026, 6262.686865656745, 6272.6236425814595, 6290.748370065192, 6322.178116844781, 5739.053056245756, 5850.3564607893095, 5909.438603332763, 5918.053111502139, 5950.761969521214, 5856.238688970407, 6315.084312774714, 6315.368463153648, 6327.145835312266, 6334.809955701337, 5742.339630942495, 6164.3835616438, 6220.906686399708, 6227.750889964442, 6244.656543890039, 6164.845878852975, 6177.752889884354, 6205.100260462208, 6231.40801623845, 6261.154744109578, 6134.038624681888, 6145.340757995316, 6186.783462578878, 6204.317525072239, 6235.781747638811, 6238.65687387189, 6246.843828904313, 6253.187085678833, 6258.968705156536, 6271.05934109878, 4897.853064408074, 4963.982383611351, 4966.428860680996, 4970.378702662168, 5001.674891132065, 4401.779911004437, 4419.9337009944875, 4432.84485043022, 4494.213512635276, 4512.300593408027, 5861.062230043258, 5887.8233652990475, 5889.970550147222, 5912.615679980788, 5913.349531551526, 6147.678249176359, 6256.43692067707, 6261.373862613793, 6265.968670156712, 6271.686415679198, 5698.572607054488, 5810.1865738796605, 5839.591228613956, 5840.3575606683235, 5863.794767183128, 5396.082665946798, 5398.2982212312145, 5402.37872644643, 5405.324334458192, 5410.350757908997, 6269.5611307208055, 6369.617822930639, 6376.840578985562, 6377.266614339317, 6379.521535884827, 6243.063540468966, 6289.528285378707, 6301.40136687014, 6303.810885673437, 6308.684565771785, 6357.841053973688, 6370.55406121567, 6379.776707815204, 6382.6170429774365, 6391.616503009835, 6099.451049405575, 6148.631082135086, 6275.843103922439, 6281.371862813682, 6292.905606415816, 6236.968815155895, 6297.842553936188, 6323.936760632335, 6331.303556608677, 6336.7465301389275, 5580.855924535379, 5945.3757355477055, 6114.266288045463, 6138.263408391022, 6149.385061493994, 4958.603311734981, 5976.312724036791, 6075.908861366995, 6101.176341193882, 6104.8836088941825, 6027.75888964454, 6044.939550604614, 6056.6194754649405, 6085.391460853879, 6095.725692343766, 6185.8144255672405, 6194.876102478015, 6211.565190436626, 6212.565120441525, 6219.315875253809, 5341.359036915595, 5974.820755377345, 6004.249468816398, 6042.214512113397, 6079.908801367895], "value": 5718.684323165686, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.3720349563046, 508.87023808928717, 544.5643485211831, 552.4641098134811, 559.7117484495485, 31646.52530212046, 34207.48688769667, 44926.32610510845, 44976.775116124416, 47429.762851185784, 46369.76815115928, 46889.0, 46921.296180557234, 46944.0, 46947.29579056317, 8729.825403491926, 9651.710448686516, 9922.553485093153, 9928.45393503357, 9957.601695932153, 8792.0, 10542.420166890817, 11898.940505297462, 12119.939400302987, 12131.454084566216, 11996.280223186637, 12068.336241506713, 12072.396380181, 12081.395930203498, 12127.0, 8838.320719063357, 8997.685081022133, 9011.729648110568, 11907.0, 11981.940090299537, 8787.78030549235, 10146.289759716823, 11591.130665200144, 11939.522419103225, 12070.27578345302, 39219.82340529789, 39587.60412395897, 42908.14183716339, 43021.35467967983, 43141.0, 3291.1607540077366, 3445.858257380252, 3459.7232221422228, 3466.3587236361354, 3812.218495208476, 30078.24804379886, 30748.077557673307, 30752.846235768677, 31148.688513114805, 31421.915151030218, 2786.150224181624, 2801.9719802801915, 2809.2555472799722, 2822.3508593023516, 2872.669642991041, 475835.0, 476367.85448218306, 477807.0, 478736.0, 479537.0, 65931.67034164799, 66209.66895165494, 66515.66742166258, 66684.33315666878, 66920.6653966727, 68631.31368686349, 69889.0, 69994.30005699878, 70116.0, 70117.0, 17333.0, 17606.647867042844, 17609.823901760818, 17637.470875873743, 17646.823531764774, 12361.381930903419, 12556.686082847818, 13267.270300133392, 13413.86586134126, 13449.731005379837, 18604.906975465037, 18631.627367452846, 18638.72041919345, 18716.812831871506, 18767.718484222747, 6836.931630683729, 6889.379955804002, 6950.513464057569, 6997.160340759141, 7019.684114214872, 755.7694903054511, 928.0997432490474, 960.6566965901033, 1110.5335758981248, 1113.3208742666966, 30184.925035120417, 30395.392092158476, 30911.0, 31124.066278011695, 31681.207969800478, 1109.2346281066039, 1112.8108221602347, 1130.7399298161304, 1135.8580177477816, 1205.8492688413949, 15566.844331556766, 15719.607009824846, 15750.369985200692, 15952.680946381239, 16045.358185672676, 677843.0, 684045.0, 684427.3114537781, 684769.0, 686092.0, 64642.0, 64646.03030954539, 64682.35317646763, 64784.0, 64928.0, 1359.5173713331822, 1481.148339704674, 1507.773833924898, 1528.2817075974274, 1529.1818876900804, 31605.84197079, 31711.682883170874, 32005.839970799996, 32398.676013239565, 32464.513032305007, 55519.722401387735, 56131.71934140303, 56293.43706562882, 56385.43614563802, 56419.43580564142, 541509.0, 542877.0, 543227.0, 544684.0, 544818.2759086179, 27325.180244592695, 28508.0, 28524.42951141007, 29270.70729292765, 41911.0, 47951.28073078837, 48907.0, 49488.752556238425, 49926.251106234165, 50066.74966625144, 38677.80661096676, 38718.0, 40186.593469228435, 40896.79551602223, 41119.7944010278, 37410.43884341683, 38656.80671596624, 39167.0, 39339.409908851965, 40211.0, 38534.61465385422, 39041.0, 39445.408318874666, 39820.40269396019, 40022.39966400448, 33828.985130446126, 34623.65376346204, 34920.12699682528, 35094.64905351016, 35128.648713512535, 24505.50988980246, 24828.053667191612, 24999.87500062488, 25659.23022309334, 26132.34669133211], "value": 20914.198628714912, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.8154196733763, 364.99635003649956, 398.20955403524016, 400.7199037476757, 413.7744929013687, 19387.806121938775, 21697.891510542424, 22055.0, 22376.216832410868, 22449.3265202044, 14030.789538156936, 21159.471013224676, 21208.787912120875, 21832.781672183275, 22374.0, 4903.632227582928, 5294.311739473863, 5477.178423236506, 5477.726113694319, 5485.0949593317055, 3953.0710283083454, 4153.875383738493, 4156.438880751105, 4172.207280616688, 4174.060836311836, 4168.8332466701295, 4178.770167640779, 5106.846794596168, 5844.532437404998, 5893.941060589382, 4949.307097006386, 5856.502197313233, 5878.118282257653, 5897.793577224798, 5927.2887253529425, 5118.129917913926, 5229.869253268661, 5459.836204913859, 5477.123660214386, 5494.450554944514, 11361.068618334903, 11818.054555635528, 13745.793813092812, 13877.722445551133, 13894.374753136133, 1605.486244401792, 2051.446109550428, 2089.9268525601533, 2139.6362618354915, 2219.4562332228707, 9900.801983960353, 10408.271421000532, 12336.568220112253, 14433.711325773531, 15185.924070379688, 1685.6628674265196, 1695.6778212139777, 1724.1810140183438, 1757.7627020352218, 1853.33280019193, 132979.0, 140446.89329660064, 141619.99999999898, 141662.0, 142748.85876711857, 11761.411929403495, 11775.88224117765, 11801.822972655416, 11812.0, 11814.0, 15573.36479669641, 15602.219889005504, 15608.765868511982, 15620.062796232263, 15621.906466547232, 8945.418547794372, 9009.099090090938, 9071.909280907106, 9075.727728168165, 9214.677486287948, 8968.0, 8973.820523589491, 8975.50634715097, 9108.453492790453, 9110.407823491452, 4737.8341758038305, 4778.737169455644, 4796.25658023007, 4804.73573953429, 4819.807207711722, 1948.7661480622417, 2006.8996550172433, 2007.5382661987824, 2013.7382140321665, 2045.4784030072533, 756.4893696754668, 834.5660256666499, 850.7575341027778, 987.3138168972639, 1020.3622735790132, 20928.895355523124, 21612.78387216108, 21831.0, 21933.451663708536, 21974.01116949741, 952.9761755956018, 967.4147140979746, 968.8982656821073, 993.3344659078434, 998.7053819123377, 10942.288751231144, 11261.042811360961, 11499.942500287612, 11505.597304094317, 11554.480048397842, 592768.0, 600189.0, 611553.0, 613222.0, 613960.0, 103477.0, 135015.0, 138090.0, 140664.0, 141763.5823641771, 1021.9080282774586, 1040.193849766437, 1070.8072546941626, 1071.1163290285544, 1079.8920107989138, 18792.154353053873, 19062.0, 19233.61532769365, 19241.230350786373, 19246.133923973735, 53056.73471632617, 53371.4662853382, 54795.17807232974, 55377.7231113858, 56210.71894640501, 79809.20190798018, 80662.5966870162, 81302.18697812947, 81895.77156342778, 82191.0, 6489.707963141765, 6850.828729281808, 8198.877016844634, 8303.543305118277, 8606.397552171287, 7895.486793358414, 9545.856812147684, 12734.872651273368, 12793.360331983178, 12994.308302744541, 9602.66390676323, 9971.102600765966, 10681.732956676145, 10805.135589153006, 11127.610533631285, 9933.3543319684, 10084.042016008467, 10785.62250321235, 11171.553137874394, 11384.0, 10982.615608453665, 11427.971482566609, 11638.882961036466, 11797.587084452003, 11917.463714133059, 8233.83532329362, 8308.667653294042, 8387.706430274911, 8517.48895066298, 8544.102869198645, 8334.583270836556, 10457.529411176667, 11226.270292430965, 11574.28004418944, 11605.535778568763], "value": 9376.140692733798, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1442.632128807154, 1624.7562865570162, 1642.3923148435078, 1840.953976150596, 1958.3733205374285, 8066.878996815052, 8750.256228220602, 8971.91028089719, 8983.505907175102, 9097.81804363914, 8711.695090671828, 8936.731898043068, 8936.91063089369, 8943.418677785956, 8947.507887066226, 2206.6910632511463, 2281.121768119278, 2283.577538155438, 2467.629855521668, 2507.1726330310953, 2159.5896779611903, 2287.3481057898503, 2311.6648086027517, 2464.2730394533655, 2553.2850801775535, 2471.159805666073, 2495.987520062397, 2503.962440563393, 2509.3475696318997, 2512.309114993373, 2477.2320580620067, 2487.664165337674, 2495.126705653025, 2499.4376265340334, 2508.3603681061213, 2178.70587470691, 2407.65089062085, 2479.962800557993, 2482.6276058591357, 2493.763092506209, 8938.284937205008, 8996.550172491348, 9005.459672419676, 9016.0, 9032.954835225848, 5385.165299378603, 5561.916571251434, 5564.165375193713, 5575.466746644664, 5586.190002449622, 3251.7886337387995, 3548.3258180945536, 3605.1167463971233, 3665.871694490707, 3691.2248427830186, 893.2898345815028, 987.274353350287, 1054.114543783218, 1062.1608928946123, 1067.4929408530963, 12159.392030398445, 12170.330631815246, 12189.99999999991, 12190.51237950472, 12195.634130976085, 4875.756212189376, 4921.360223170965, 5040.092783298968, 5047.924281135786, 5060.418051924003, 10279.074883260542, 10298.536565854554, 10299.0, 10306.845397319046, 10315.84526232107, 5499.642523235977, 5572.4706152915405, 5577.27495425592, 5581.0, 5587.916181257285, 4540.160070386989, 4897.902041959141, 4991.251312303184, 5076.340075790197, 5117.257997590328, 3482.495038219444, 3869.941950870739, 4130.91738165235, 4213.726107802983, 4225.302825033897, 1167.2569461764786, 1305.556110922272, 1355.6949686320697, 1392.8398234203196, 1403.368484182122, 819.8358331169753, 824.583585289425, 908.8227795579799, 909.6997990663066, 940.7130825098411, 5652.123920792284, 5846.619969701956, 5930.28836539618, 5972.074328479092, 6050.515958723292, 967.2165545907837, 979.5983646704817, 979.8383266761048, 980.9509524523888, 988.7033889833021, 3889.086064774776, 3953.723239373216, 3958.4062390641634, 4020.979895100506, 4046.5751096135073, 9556.0, 10099.69700908974, 10881.891181088246, 10940.398278094623, 11101.1674124441, 10899.782004359868, 10981.39602321864, 11002.779944401067, 11003.394813285186, 11024.724381890357, 8014.959925200454, 8273.172682731678, 8360.498370097814, 8362.247397734232, 8414.242718155396, 6038.184845046038, 6053.364396738279, 6175.722092505938, 6192.0, 6218.81343559694, 10158.0, 10458.68623941283, 10460.110890574082, 10468.790624187626, 10480.895191047992, 10100.646477373257, 10276.0, 10653.627123050655, 10662.8400573993, 10691.144708423462, 7185.389241914496, 7441.888371674538, 7838.686452541834, 7975.800604984922, 7996.520208787491, 8293.295069918882, 8581.65673373058, 8585.356098292528, 8698.13018698125, 8714.869276960977, 7506.211847756125, 7599.772006839804, 7685.154632990255, 7733.149353570991, 7843.647035883285, 7470.028896243453, 7472.962635186789, 7478.551286922802, 7575.015248017722, 7652.120006199136, 7128.28717128283, 7203.279672032755, 7214.927850721426, 7225.602591857499, 7232.0959880015, 4769.928451073168, 4848.733319667452, 4962.602991760723, 4974.32846565709, 5154.407243167084, 4933.802647894044, 4998.075356059141, 5031.798728050837, 5112.97443512795, 5276.129438642581], "value": 4702.02871830758, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.1231633685827, 313.8713127617676, 326.2138246825151, 329.58966087221415, 329.7708092875451, 4456.331550267458, 4676.532346765322, 5325.08297013075, 5354.14333706607, 5428.4300148484335, 3705.6850167735774, 4407.537208593099, 5042.881989564383, 5095.312132862063, 5283.0, 3398.674516938396, 3664.9450258246147, 3745.981270093646, 4026.3960405939124, 4059.9797001014954, 3898.894596917661, 3917.334053210961, 3992.6425015494724, 4049.3521036634143, 4070.8982275443054, 3370.511275864998, 3719.9442008369897, 3826.9043273918096, 3883.893090469203, 3923.5880232575596, 3339.782914110575, 3404.540387047766, 3942.2062961352517, 3987.820548075329, 4014.0, 3497.9125521862156, 3534.469829525566, 3540.7521473496868, 3543.255916257589, 3545.2377738786026, 4032.3951407288846, 4647.465541462741, 5051.6716413433005, 5119.846404607868, 5238.476152384769, 4096.50113430807, 4267.893302667427, 4571.0, 4589.586937175637, 4659.277811939155, 4475.328700694889, 4912.852614421573, 5542.418046105182, 5571.944280557183, 5583.609147359686, 3533.922153743107, 3726.9937116978263, 3758.605346438612, 3825.9617403825882, 3962.346212874872, 5670.5463562914865, 5727.0, 5748.5688573357165, 5759.942400575983, 5853.619514731571, 3389.3560223557447, 3572.1069732566857, 3862.092408284051, 3955.248502784462, 3996.580359062257, 3603.6756691897854, 3625.169289508834, 3975.980120099381, 3984.0438294809037, 4093.1813637272667, 3348.5981682198294, 3742.9438558421643, 4167.666346769065, 4539.319102134707, 4560.498345182027, 4500.752458614742, 4876.780544875489, 4933.186024305949, 4997.175466048134, 5071.670341427869, 1127.971800704989, 1349.9402968669547, 1351.1892864281353, 1351.9256440895845, 1355.1801160297878, 857.8804659918866, 858.52780970466, 860.4536119564121, 872.1815015692353, 911.6125646600168, 4382.452193475816, 5142.331496905378, 5188.365664815571, 5203.2195170723935, 5228.2680424740565, 4697.084068606658, 5629.690367029771, 5654.886902261932, 5717.742701578356, 5765.250517432707, 4000.3599424092004, 4179.874603761831, 4550.930531325136, 5068.873278168076, 5219.269302297681, 2981.0013645429026, 2990.566367876646, 3017.743491803177, 3030.075826872825, 3086.1791542989745, 5287.123071309629, 5382.712347252402, 5439.836804895859, 5955.40445955401, 5965.492933100735, 5338.199270109437, 5619.342294023273, 5908.350081491032, 5922.7927022554, 5924.170616113747, 5072.985402919431, 5895.322037965603, 5901.2033375495475, 5930.644161350333, 6006.399360064046, 5246.6065045121, 5464.781408743605, 5647.491725744702, 5678.346990096192, 5785.991284532104, 4994.9500504994485, 5628.915566266427, 5685.971570142288, 5740.569457290637, 5860.241927421842, 5064.772085256246, 5906.557008224315, 6078.878422431439, 6079.817605471843, 6131.570790044654, 3800.0, 3926.3325234710737, 3929.135590170158, 3945.723799334019, 3949.9407508887966, 5760.020796464695, 5808.390119037609, 5829.417058294137, 5875.559333049953, 5882.999890018621, 4396.944733263929, 4972.65191436596, 4997.75056235941, 5727.455891690282, 5851.414858514115, 4612.146752850734, 5192.766325515286, 5383.7308134593895, 5549.667019978814, 5602.467765562265, 4768.354917553577, 4828.5412885777205, 5175.499105259432, 5416.995711586738, 5448.400675925565, 4717.938463845745, 4944.728039957837, 5157.845264641916, 5335.253064570886, 5586.748596313095, 4725.50382209863, 4764.857054288377, 5377.542908852635, 5498.718032016668, 5564.165375193753], "value": 4006.088310611698, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [643.7553729582758, 665.7536711416775, 686.241702918275, 709.6522703875099, 734.8934404511346, 12949.35253237338, 14880.032797868145, 15597.376104955789, 15604.297806598703, 15650.217489125554, 13237.205767653948, 14364.20996845173, 14411.855881441183, 14442.350094245758, 14507.854921450782, 910.4036855859406, 913.4108500017485, 925.296774451415, 926.7451450851003, 927.601131513449, 919.4850883505225, 923.3075193604797, 923.5105394141083, 924.8242833861544, 926.6525053105086, 962.5379817687514, 963.7831487915198, 964.753987733127, 965.1988849255101, 965.946872921989, 960.8606752020987, 960.9375390599589, 961.711486554031, 964.6382606522554, 964.9915837949325, 716.1763971432841, 717.2683862460261, 718.3247747117692, 719.863225987066, 722.3607107709714, 13179.275139867303, 13230.338483075808, 13265.137766045176, 13268.137571057947, 13273.734525309535, 11098.61154859576, 11109.777804443947, 11284.097272218203, 11323.943380283128, 11590.072794176445, 9101.180893719531, 9354.251659867263, 9651.179649729782, 10083.394996300172, 10093.293469457141, 3954.3787047310752, 4035.233305671913, 4204.348326009474, 4289.313709806447, 4306.0, 53961.46038539643, 67921.0, 69994.0, 83652.0, 85163.57418212932, 7625.542467451971, 9400.294977876687, 9446.38598491096, 9463.905360946303, 9659.0, 20980.685289720666, 21538.461538461663, 21576.0, 21599.244026458997, 21640.675389869164, 7411.6664750086375, 7493.3630641395, 7515.624218789038, 7538.170801211862, 7620.885686714703, 3690.7970061646865, 3919.8824035278985, 3999.1801680655394, 4010.3583426651594, 4055.0, 3359.7480188985437, 3500.492428597839, 3586.946195807065, 3679.7056235501095, 3680.5399325084363, 640.1486023588616, 647.1134545672459, 658.9538732288789, 660.6696651674163, 670.735059651435, 535.5019831556612, 576.7375843990974, 607.7629724407485, 619.1146660275849, 625.1373105114913, 7926.207379262028, 7941.483803552866, 8129.024517057991, 8171.713990010321, 8285.171482851667, 575.3096284458583, 591.6420565557776, 614.5145335185243, 619.2012304127715, 630.3538872655565, 3514.4201206801104, 3537.1510837399355, 3837.347650899298, 3900.7074469414906, 3992.7803970781306, 88651.0, 90965.54517227372, 92133.53933230425, 92990.53504732433, 94129.5293523528, 81526.59236703778, 82414.0, 83474.9131271711, 84188.57905710554, 85249.57375213085, 62867.6856615714, 69361.26596835018, 69969.0, 70284.24289392807, 74828.0, 8098.7165449209, 8129.552874591955, 8263.462874913112, 8313.293370063613, 8314.877491538566, 41428.34286628501, 45193.54806451893, 46207.53792462167, 46303.74142569286, 46979.06041879075, 62162.68918655378, 62424.0, 63857.68071159614, 65051.67474162599, 65101.0, 10902.56389744433, 11025.724356891142, 11089.22375433712, 11375.43122843838, 11401.543938242377, 10042.69871903844, 12343.876561234632, 12395.690107747378, 13041.217526948414, 13073.15024523403, 11434.08527317796, 11536.711582210177, 11637.301761893974, 11705.648830535098, 11928.582499612472, 11448.94275528617, 11659.591914282959, 11922.880771192524, 11925.522979081086, 11927.224730392496, 8706.738797836075, 11019.724506887393, 11083.944580277048, 11272.267302625303, 11620.186586939171, 8500.53247071417, 9567.01932585602, 9591.760205994906, 9714.070431471946, 9743.853842192231, 7744.5740484271655, 8836.823263534565, 8867.467951922905, 9367.531623418938, 9385.953070234606], "value": 6834.657612346187, "unit": "score"}], "name": "dromaeo_css", "value": 7243.057495237132, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 113.0, 112.0, 111.0, 111.0, 111.0, 111.0, 120.0, 163.0], "value": 117.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 117.0, 141.0, 193.0, 143.0, 138.0, 117.0, 149.0, 155.0, 118.0], "value": 142.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 184.0, 184.0, 183.0, 184.0, 187.0, 184.0, 179.0, 184.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 57.0, 155.0, 80.0, 85.0, 55.0, 58.0, 113.0, 56.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 133.0, 78.0, 79.0, 77.0, 130.0, 113.0, 113.0, 102.0], "value": 101.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 90.0, 85.0, 90.0, 85.0, 88.0, 86.0, 88.0, 85.0], "value": 87.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 96.0, 112.0, 117.0, 94.0, 99.0, 147.0, 95.0, 99.0], "value": 104.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 132.0, 84.0, 84.0, 86.0, 83.0, 84.0, 86.0, 86.0, 84.0], "value": 93.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 57.0, 56.0, 58.0, 55.0, 55.0, 120.0, 57.0, 56.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 82.0, 100.0, 100.0, 100.0, 69.0, 66.0, 68.0, 67.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 83.0, 97.0, 106.0, 105.0, 106.0, 85.0, 84.0, 85.0], "value": 92.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 147.0, 141.0, 144.0, 142.0, 143.0, 146.0, 133.0, 217.0, 153.0], "value": 150.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 164.0, 163.0, 162.0, 162.0, 164.0, 163.0, 162.0, 180.0], "value": 164.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 65.0, 65.0, 64.0, 65.0, 64.0, 64.0, 65.0], "value": 64.9, "unit": "score"}], "name": "kraken", "value": 1523.1, "alertThreshold": 2.0}]} 16:35:41 INFO - Return code: 0 16:35:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:35:41 ERROR - # TBPL SUCCESS # 16:35:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:35:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:35:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:35:41 INFO - 2016-05-17 16:35:41.552 screenresolution[1681:9680] starting screenresolution argv=screenresolution get 16:35:41 INFO - 2016-05-17 16:35:41.555 screenresolution[1681:9680] Display 0: 1600x1200x32@60 16:35:41 INFO - 2016-05-17 16:35:41.561 screenresolution[1682:9683] starting screenresolution argv=screenresolution list 16:35:41 INFO - Available Modes on Display 0 16:35:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:35:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:35:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:35:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:35:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:35:41 INFO - Intel Iris: 16:35:41 INFO - Chipset Model: Intel Iris 16:35:41 INFO - Type: GPU 16:35:41 INFO - Bus: Built-In 16:35:41 INFO - VRAM (Dynamic, Max): 1536 MB 16:35:41 INFO - Vendor: Intel (0x8086) 16:35:41 INFO - Device ID: 0x0a2e 16:35:41 INFO - Revision ID: 0x0009 16:35:41 INFO - Displays: 16:35:41 INFO - Display: 16:35:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:35:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:35:41 INFO - Display Serial Number: 1600x1200 60 16:35:41 INFO - Main Display: Yes 16:35:41 INFO - Mirror: Off 16:35:41 INFO - Online: Yes 16:35:41 INFO - Rotation: Supported 16:35:41 INFO - Return code: 0 16:35:41 INFO - Running post-action listener: _resource_record_post_action 16:35:41 INFO - ##### 16:35:41 INFO - ##### Finished run-tests step (success) 16:35:41 INFO - ##### 16:35:41 INFO - Running post-run listener: _resource_record_post_run 16:35:41 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 31346176; Write bytes: 695919104; Read time: 194; Write time: 1936 16:35:41 INFO - TinderboxPrint: CPU usage
53.7% 16:35:41 INFO - TinderboxPrint: I/O read bytes / time
31,346,176 / 194 16:35:41 INFO - TinderboxPrint: I/O write bytes / time
695,919,104 / 1,936 16:35:41 INFO - TinderboxPrint: CPU idle
2,248.4 (46.3%) 16:35:41 INFO - TinderboxPrint: CPU system
972.4 (20.0%) 16:35:41 INFO - TinderboxPrint: CPU user
1,632.9 (33.6%) 16:35:41 INFO - TinderboxPrint: Swap in / out
422,842,368 / 0 16:35:41 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 385051136; Write bytes: 381007360; Read time: 29674; Write time: 804 16:35:41 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 29580800; Write bytes: 295480320; Read time: 181; Write time: 1080 16:35:42 INFO - Running post-run listener: _upload_blobber_files 16:35:42 INFO - Blob upload gear active. 16:35:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:35:42 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 16:35:42 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'] 16:35:42 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 16:35:42 INFO - (blobuploader) - INFO - Open directory for files ... 16:35:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:35:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:35:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:35:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:35:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:35:43 INFO - (blobuploader) - INFO - Done attempting. 16:35:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:35:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:35:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:35:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:35:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:35:45 INFO - (blobuploader) - INFO - Done attempting. 16:35:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:35:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:35:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:35:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:35:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:35:47 INFO - (blobuploader) - INFO - Done attempting. 16:35:47 INFO - (blobuploader) - INFO - Iteration through files over. 16:35:47 INFO - Return code: 0 16:35:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:35:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:35:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/88fc5a52c345b673f0016934e380a829f3877fafa9e758322866532bd5840d5c7d35973c14cabc8da74084dadb2678d0b6370a0057b36c4f879212d7a711af21", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0352fd740a9641f2ccfdf06940a5218ea5821a77b5f0a696cef3088346b1231e1f22d6a119dd922f0053e93b73d6ac6d60cf501365b83afbe0fbbbf60bb60b73", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/198bd2267c11bc779cb856c12b9db19af11a3670801319f25fbc647945dc0764661edaf1f4015f3b6c2d32266b304759841fe3783627df3c5ea523af8e1d552a"} 16:35:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:35:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:35:47 INFO - Contents: 16:35:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/88fc5a52c345b673f0016934e380a829f3877fafa9e758322866532bd5840d5c7d35973c14cabc8da74084dadb2678d0b6370a0057b36c4f879212d7a711af21", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0352fd740a9641f2ccfdf06940a5218ea5821a77b5f0a696cef3088346b1231e1f22d6a119dd922f0053e93b73d6ac6d60cf501365b83afbe0fbbbf60bb60b73", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/198bd2267c11bc779cb856c12b9db19af11a3670801319f25fbc647945dc0764661edaf1f4015f3b6c2d32266b304759841fe3783627df3c5ea523af8e1d552a"} 16:35:47 INFO - Running post-run listener: copy_logs_to_upload_dir 16:35:47 INFO - Copying logs to upload dir... 16:35:47 INFO - mkdir: /builds/slave/test/build/upload/logs 16:35:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1294.090028 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 34 secs) (at 2016-05-17 16:35:47.442127) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 16:35:47.445503) ========= 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.agQUoLLMj4/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.RPvnWZ5C9w/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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/88fc5a52c345b673f0016934e380a829f3877fafa9e758322866532bd5840d5c7d35973c14cabc8da74084dadb2678d0b6370a0057b36c4f879212d7a711af21", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0352fd740a9641f2ccfdf06940a5218ea5821a77b5f0a696cef3088346b1231e1f22d6a119dd922f0053e93b73d6ac6d60cf501365b83afbe0fbbbf60bb60b73", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/198bd2267c11bc779cb856c12b9db19af11a3670801319f25fbc647945dc0764661edaf1f4015f3b6c2d32266b304759841fe3783627df3c5ea523af8e1d552a"} build_url:https://queue.taskcluster.net/v1/task/PFZJGmDKTtizPf7bkQRIbQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011149 build_url: 'https://queue.taskcluster.net/v1/task/PFZJGmDKTtizPf7bkQRIbQ/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/88fc5a52c345b673f0016934e380a829f3877fafa9e758322866532bd5840d5c7d35973c14cabc8da74084dadb2678d0b6370a0057b36c4f879212d7a711af21", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0352fd740a9641f2ccfdf06940a5218ea5821a77b5f0a696cef3088346b1231e1f22d6a119dd922f0053e93b73d6ac6d60cf501365b83afbe0fbbbf60bb60b73", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/198bd2267c11bc779cb856c12b9db19af11a3670801319f25fbc647945dc0764661edaf1f4015f3b6c2d32266b304759841fe3783627df3c5ea523af8e1d552a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-17 16:35:47.474423) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 16:35:47.474745) ========= 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.agQUoLLMj4/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.RPvnWZ5C9w/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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.005107 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-17 16:35:47.523897) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 16:35:47.524219) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-17 16:35:47.524562) ========= ========= Total master_lag: 0.20 =========