builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0220 starttime: 1463994133.23 results: success (0) buildid: 20160523001034 builduid: f08cb13134684cee8acd4227f8504fbb revision: 64ad199ede21c4ab89e4b65b5b62351a3bbe5a45 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 02:02:13.229394) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 02:02:13.229893) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 02:02:13.230172) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 02:02:13.270478) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 02:02:13.270910) ========= 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.LNK7LvzO9c/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.RtWn47hrxU/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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-23 02:02:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.19M=0.001s 2016-05-23 02:02:12 (8.19 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117316 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 02:02:13.406757) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 02:02:13.407088) ========= 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.LNK7LvzO9c/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.RtWn47hrxU/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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.020119 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 02:02:13.455556) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-05-23 02:02:13.455921) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 64ad199ede21c4ab89e4b65b5b62351a3bbe5a45 --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 64ad199ede21c4ab89e4b65b5b62351a3bbe5a45 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LNK7LvzO9c/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.RtWn47hrxU/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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-23 02:02:13,079 truncating revision to first 12 chars 2016-05-23 02:02:13,079 Setting DEBUG logging. 2016-05-23 02:02:13,079 attempt 1/10 2016-05-23 02:02:13,079 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/64ad199ede21?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-23 02:02:15,494 unpacking tar archive at: fx-team-64ad199ede21/testing/mozharness/ program finished with exit code 0 elapsedTime=3.015466 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-05-23 02:02:16.489944) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 02:02:16.490277) ========= script_repo_revision: 64ad199ede21c4ab89e4b65b5b62351a3bbe5a45 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 02:02:16.490723) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 02:02:16.490999) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 02:02:16.505607) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-23 02:02:16.505965) ========= /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.LNK7LvzO9c/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.RtWn47hrxU/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 02:02:16 INFO - MultiFileLogger online at 20160523 02:02:16 in /builds/slave/test 02:02:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 02:02:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:02:16 INFO - {'append_to_log': False, 02:02:16 INFO - 'base_work_dir': '/builds/slave/test', 02:02:16 INFO - 'blob_upload_branch': 'Fx-Team', 02:02:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:02:16 INFO - 'branch': 'Fx-Team', 02:02:16 INFO - 'buildbot_json_path': 'buildprops.json', 02:02:16 INFO - 'config_files': ('talos/mac_config.py',), 02:02:16 INFO - 'default_actions': ('clobber', 02:02:16 INFO - 'read-buildbot-config', 02:02:16 INFO - 'download-and-extract', 02:02:16 INFO - 'populate-webroot', 02:02:16 INFO - 'create-virtualenv', 02:02:16 INFO - 'install', 02:02:16 INFO - 'run-tests'), 02:02:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:02:16 INFO - 'download_minidump_stackwalk': True, 02:02:16 INFO - 'download_symbols': 'ondemand', 02:02:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:02:16 INFO - 'tooltool.py': '/tools/tooltool.py', 02:02:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:02:16 INFO - '/tools/misc-python/virtualenv.py')}, 02:02:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:02:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:02:16 INFO - 'installer_path': 'installer.exe', 02:02:16 INFO - 'log_level': 'info', 02:02:16 INFO - 'log_name': 'talos', 02:02:16 INFO - 'log_to_console': True, 02:02:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:02:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:02:16 INFO - 'opt_config_files': (), 02:02:16 INFO - 'pip_index': False, 02:02:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:02:16 INFO - 'cmd': ('bash', 02:02:16 INFO - '-c', 02:02:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:02:16 INFO - 'enabled': True, 02:02:16 INFO - 'halt_on_failure': False, 02:02:16 INFO - 'name': 'check_screen_resolution'},), 02:02:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:02:16 INFO - 'cmd': ('bash', 02:02:16 INFO - '-c', 02:02:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:02:16 INFO - 'enabled': True, 02:02:16 INFO - 'halt_on_failure': False, 02:02:16 INFO - 'name': 'check_screen_resolution'},), 02:02:16 INFO - 'run_cmd_checks_enabled': True, 02:02:16 INFO - 'sps_profile': False, 02:02:16 INFO - 'sps_profile_interval': 0, 02:02:16 INFO - 'suite': 'dromaeojs', 02:02:16 INFO - 'system_bits': '32', 02:02:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:02:16 INFO - 'title': 't-yosemite-r7-0220', 02:02:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:02:16 INFO - 'use_talos_json': True, 02:02:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:02:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:02:16 INFO - 'work_dir': 'build'} 02:02:16 INFO - ##### 02:02:16 INFO - ##### Running clobber step. 02:02:16 INFO - ##### 02:02:16 INFO - Running pre-action listener: _resource_record_pre_action 02:02:16 INFO - Running main action method: clobber 02:02:16 INFO - rmtree: /builds/slave/test/build 02:02:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:02:18 INFO - Running post-action listener: _resource_record_post_action 02:02:18 INFO - ##### 02:02:18 INFO - ##### Finished clobber step (success) 02:02:18 INFO - ##### 02:02:18 INFO - ##### 02:02:18 INFO - ##### Running read-buildbot-config step. 02:02:18 INFO - ##### 02:02:18 INFO - Running pre-action listener: _resource_record_pre_action 02:02:18 INFO - Running main action method: read_buildbot_config 02:02:18 INFO - Using buildbot properties: 02:02:18 INFO - { 02:02:18 INFO - "project": "", 02:02:18 INFO - "product": "firefox", 02:02:18 INFO - "script_repo_revision": "production", 02:02:18 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 02:02:18 INFO - "repository": "", 02:02:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 02:02:18 INFO - "buildid": "20160523001034", 02:02:18 INFO - "pgo_build": "False", 02:02:18 INFO - "basedir": "/builds/slave/test", 02:02:18 INFO - "buildnumber": 20, 02:02:18 INFO - "slavename": "t-yosemite-r7-0220", 02:02:18 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 02:02:18 INFO - "platform": "yosemite_r7", 02:02:18 INFO - "scheduler": "tests-fx-team-macosx64-talos", 02:02:18 INFO - "branch": "fx-team", 02:02:18 INFO - "repo_path": "integration/fx-team", 02:02:18 INFO - "revision": "64ad199ede21c4ab89e4b65b5b62351a3bbe5a45", 02:02:18 INFO - "stage_platform": "macosx64", 02:02:18 INFO - "builduid": "f08cb13134684cee8acd4227f8504fbb", 02:02:18 INFO - "slavebuilddir": "test" 02:02:18 INFO - } 02:02:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 02:02:18 INFO - Running post-action listener: _resource_record_post_action 02:02:18 INFO - ##### 02:02:18 INFO - ##### Finished read-buildbot-config step (success) 02:02:18 INFO - ##### 02:02:18 INFO - ##### 02:02:18 INFO - ##### Running download-and-extract step. 02:02:18 INFO - ##### 02:02:18 INFO - Running pre-action listener: _resource_record_pre_action 02:02:18 INFO - Running main action method: download_and_extract 02:02:18 INFO - mkdir: /builds/slave/test/build/tests 02:02: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')]} 02:02:18 INFO - https://queue.taskcluster.net/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:02:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 02:02:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 02:02:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/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 02:02:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/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 02:02:19 INFO - Downloaded 1199 bytes. 02:02:19 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 02:02:19 INFO - Using the following test package requirements: 02:02:19 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 02:02:19 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:02:19 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 02:02:19 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:02:19 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 02:02:19 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:02:19 INFO - u'jsshell-mac.zip'], 02:02:19 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:02:19 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 02:02:19 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 02:02:19 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:02:19 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 02:02:19 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:02:19 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 02:02:19 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:02:19 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 02:02:19 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 02:02:19 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 02:02:19 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:02: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')]} 02:02:19 INFO - https://queue.taskcluster.net/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:02:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:02:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:02:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/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 02:02:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/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 02:02:20 INFO - Downloaded 18630557 bytes. 02:02:20 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'] 02:02:20 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 02:02:23 INFO - Return code: 0 02:02:23 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 02:02: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')]} 02:02:23 INFO - https://queue.taskcluster.net/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:02:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:02:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 02:02:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/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 02:02:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/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 02:02:25 INFO - Downloaded 18630557 bytes. 02:02:25 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'] 02:02:25 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 02:02:28 INFO - Return code: 0 02:02:28 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')]} 02:02:28 INFO - https://queue.taskcluster.net/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:02:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 02:02:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 02:02:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/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 02:02:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/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 02:02:30 INFO - Downloaded 10840359 bytes. 02:02:30 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'] 02:02:30 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 02:02:30 INFO - Return code: 0 02:02: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')]} 02:02:30 INFO - https://queue.taskcluster.net/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 02:02:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:02:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:02:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 02:02:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/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 02:02:33 INFO - Downloaded 110153075 bytes. 02:02:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:02:33 INFO - mkdir: /builds/slave/test/properties 02:02:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:02:33 INFO - Writing to file /builds/slave/test/properties/build_url 02:02:33 INFO - Contents: 02:02:33 INFO - build_url:https://queue.taskcluster.net/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 02:02:34 INFO - Running post-action listener: _resource_record_post_action 02:02:34 INFO - Running post-action listener: set_extra_try_arguments 02:02:34 INFO - ##### 02:02:34 INFO - ##### Finished download-and-extract step (success) 02:02:34 INFO - ##### 02:02:34 INFO - ##### 02:02:34 INFO - ##### Running populate-webroot step. 02:02:34 INFO - ##### 02:02:34 INFO - Running pre-action listener: _resource_record_pre_action 02:02:34 INFO - Running main action method: populate_webroot 02:02:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:02:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:02:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:02:34 INFO - '--tppagecycles', 02:02:34 INFO - '7'], 02:02:34 INFO - 'tests': ['tsvgm']}}, 02:02:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:02:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:02:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:02:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:02:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:02:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:02:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:02:34 INFO - 'talos_options': ['--disable-e10s'], 02:02:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:02:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:02:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:02:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:02:34 INFO - 'talos_options': ['--disable-e10s'], 02:02:34 INFO - 'tests': ['damp', 'tps']}, 02:02:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:02:34 INFO - 'tests': ['damp', 'tps']}, 02:02:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:02:34 INFO - 'tests': ['dromaeo_dom']}, 02:02:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:02:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:02:34 INFO - 'tests': ['a11yr', 02:02:34 INFO - 'ts_paint', 02:02:34 INFO - 'tpaint', 02:02:34 INFO - 'sessionrestore', 02:02:34 INFO - 'sessionrestore_no_auto_restore', 02:02:34 INFO - 'tabpaint']}, 02:02:34 INFO - 'other-e10s': {'tests': ['a11yr', 02:02:34 INFO - 'ts_paint', 02:02:34 INFO - 'tpaint', 02:02:34 INFO - 'sessionrestore', 02:02:34 INFO - 'sessionrestore_no_auto_restore', 02:02:34 INFO - 'tabpaint']}, 02:02:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:02:34 INFO - 'ts_paint', 02:02:34 INFO - 'tpaint', 02:02:34 INFO - 'sessionrestore', 02:02:34 INFO - 'sessionrestore_no_auto_restore', 02:02:34 INFO - 'tabpaint']}, 02:02:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:02:34 INFO - 'ts_paint', 02:02:34 INFO - 'tpaint', 02:02:34 INFO - 'sessionrestore', 02:02:34 INFO - 'sessionrestore_no_auto_restore', 02:02:34 INFO - 'tabpaint']}, 02:02:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:02:34 INFO - 'tests': ['a11yr', 02:02:34 INFO - 'ts_paint', 02:02:34 INFO - 'tpaint', 02:02:34 INFO - 'sessionrestore', 02:02:34 INFO - 'sessionrestore_no_auto_restore', 02:02:34 INFO - 'tabpaint']}, 02:02:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:02:34 INFO - 'tests': ['a11yr', 02:02:34 INFO - 'ts_paint', 02:02:34 INFO - 'tpaint', 02:02:34 INFO - 'sessionrestore', 02:02:34 INFO - 'sessionrestore_no_auto_restore', 02:02:34 INFO - 'tabpaint']}, 02:02:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:02:34 INFO - 'tests': ['tsvgx', 02:02:34 INFO - 'tsvgr_opacity', 02:02:34 INFO - 'tart', 02:02:34 INFO - 'tscrollx', 02:02:34 INFO - 'cart']}, 02:02:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:02:34 INFO - 'tsvgr_opacity', 02:02:34 INFO - 'tart', 02:02:34 INFO - 'tscrollx', 02:02:34 INFO - 'cart']}, 02:02:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:02:34 INFO - 'talos_options': ['--disable-e10s'], 02:02:34 INFO - 'tests': ['tp5o']}, 02:02:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:02:34 INFO - 'tests': ['tp5o']}, 02:02:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:02:34 INFO - 'talos_options': ['--disable-e10s', 02:02:34 INFO - '--xperf_path', 02:02:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:02:34 INFO - 'tests': ['tp5n']}, 02:02:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:02:34 INFO - 'talos_options': ['--xperf_path', 02:02:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:02:34 INFO - 'tests': ['tp5n']}}, 02:02:34 INFO - 'talos.zip': {'path': '', 02:02:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:02:34 INFO - Running post-action listener: _resource_record_post_action 02:02:34 INFO - ##### 02:02:34 INFO - ##### Finished populate-webroot step (success) 02:02:34 INFO - ##### 02:02:34 INFO - ##### 02:02:34 INFO - ##### Running create-virtualenv step. 02:02:34 INFO - ##### 02:02:34 INFO - Running pre-action listener: _resource_record_pre_action 02:02:34 INFO - Running main action method: create_virtualenv 02:02:34 INFO - Creating virtualenv /builds/slave/test/build/venv 02:02:34 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 02:02:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:02:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:02:34 INFO - Using real prefix '/tools/python27' 02:02:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:02:34 INFO - Installing distribute.............................................................................................................................................................................................done. 02:02:37 INFO - Installing pip.................done. 02:02:37 INFO - Return code: 0 02:02:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:02:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:02:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:02:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:37 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 0x10af97a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af9d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda88ce7f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3bd30>, '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 0x10ac5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae32b20>, '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.LNK7LvzO9c/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.RtWn47hrxU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 02:02:37 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 02:02:37 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 02:02:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNK7LvzO9c/Render', 02:02:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:02:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:02:37 INFO - 'HOME': '/Users/cltbld', 02:02:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:02:37 INFO - 'LOGNAME': 'cltbld', 02:02:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:02:37 INFO - 'MOZ_NO_REMOTE': '1', 02:02:37 INFO - 'NO_EM_RESTART': '1', 02:02:37 INFO - 'PAGER': '/bin/cat', 02:02:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:02:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:02:37 INFO - 'PWD': '/builds/slave/test', 02:02:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:02:37 INFO - 'SHELL': '/bin/bash', 02:02:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RtWn47hrxU/Listeners', 02:02:37 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 02:02:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:02:37 INFO - 'USER': 'cltbld', 02:02:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:02:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:02:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:02:37 INFO - 'XPC_FLAGS': '0x0', 02:02:37 INFO - 'XPC_SERVICE_NAME': '0', 02:02:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:02:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:02:38 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 02:02:38 INFO - Cleaning up... 02:02:38 INFO - Return code: 0 02:02:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:02:38 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')]} 02:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:02:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:38 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')]} 02:02:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:02:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:38 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 0x10af97a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af9d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda88ce7f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3bd30>, '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 0x10ac5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae32b20>, '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.LNK7LvzO9c/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.RtWn47hrxU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 02:02:38 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 02:02:38 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 02:02:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNK7LvzO9c/Render', 02:02:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:02:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:02:38 INFO - 'HOME': '/Users/cltbld', 02:02:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:02:38 INFO - 'LOGNAME': 'cltbld', 02:02:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:02:38 INFO - 'MOZ_NO_REMOTE': '1', 02:02:38 INFO - 'NO_EM_RESTART': '1', 02:02:38 INFO - 'PAGER': '/bin/cat', 02:02:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:02:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:02:38 INFO - 'PWD': '/builds/slave/test', 02:02:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:02:38 INFO - 'SHELL': '/bin/bash', 02:02:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RtWn47hrxU/Listeners', 02:02:38 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 02:02:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:02:38 INFO - 'USER': 'cltbld', 02:02:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:02:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:02:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:02:38 INFO - 'XPC_FLAGS': '0x0', 02:02:38 INFO - 'XPC_SERVICE_NAME': '0', 02:02:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:02:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:02:38 INFO - Downloading/unpacking psutil>=3.1.1 02:02:38 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 02:02:38 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 02:02:38 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 02:02:38 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 02:02:38 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 02:02:38 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 02:02:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:02:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:02:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:02:41 INFO - Installing collected packages: psutil 02:02:41 INFO - Running setup.py install for psutil 02:02:41 INFO - building 'psutil._psutil_osx' extension 02:02:41 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 02:02:41 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 02:02:41 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 02:02:41 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 02:02:41 INFO - building 'psutil._psutil_posix' extension 02:02:41 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 02:02:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:02:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:02:41 INFO - ^ 02:02:41 INFO - 1 warning generated. 02:02:41 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 02:02:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:02:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:02:42 INFO - Successfully installed psutil 02:02:42 INFO - Cleaning up... 02:02:42 INFO - Return code: 0 02:02:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:02:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:02:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:02:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10af97a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af9d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda88ce7f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3bd30>, '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 0x10ac5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae32b20>, '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.LNK7LvzO9c/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.RtWn47hrxU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 02:02:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:02:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:02:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNK7LvzO9c/Render', 02:02:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:02:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:02:42 INFO - 'HOME': '/Users/cltbld', 02:02:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:02:42 INFO - 'LOGNAME': 'cltbld', 02:02:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:02:42 INFO - 'MOZ_NO_REMOTE': '1', 02:02:42 INFO - 'NO_EM_RESTART': '1', 02:02:42 INFO - 'PAGER': '/bin/cat', 02:02:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:02:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:02:42 INFO - 'PWD': '/builds/slave/test', 02:02:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:02:42 INFO - 'SHELL': '/bin/bash', 02:02:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RtWn47hrxU/Listeners', 02:02:42 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 02:02:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:02:42 INFO - 'USER': 'cltbld', 02:02:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:02:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:02:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:02:42 INFO - 'XPC_FLAGS': '0x0', 02:02:42 INFO - 'XPC_SERVICE_NAME': '0', 02:02:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:02:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:02:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:02:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:02:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:02:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:02:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:02:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:02:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:02:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:02:44 INFO - Installing collected packages: mozsystemmonitor 02:02:44 INFO - Running setup.py install for mozsystemmonitor 02:02:45 INFO - Successfully installed mozsystemmonitor 02:02:45 INFO - Cleaning up... 02:02:45 INFO - Return code: 0 02:02:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:02: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')]} 02:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:02:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02: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')]} 02:02:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:02:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10af97a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af9d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda88ce7f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3bd30>, '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 0x10ac5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae32b20>, '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.LNK7LvzO9c/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.RtWn47hrxU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 02:02:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:02:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:02:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNK7LvzO9c/Render', 02:02:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:02:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:02:45 INFO - 'HOME': '/Users/cltbld', 02:02:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:02:45 INFO - 'LOGNAME': 'cltbld', 02:02:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:02:45 INFO - 'MOZ_NO_REMOTE': '1', 02:02:45 INFO - 'NO_EM_RESTART': '1', 02:02:45 INFO - 'PAGER': '/bin/cat', 02:02:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:02:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:02:45 INFO - 'PWD': '/builds/slave/test', 02:02:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:02:45 INFO - 'SHELL': '/bin/bash', 02:02:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RtWn47hrxU/Listeners', 02:02:45 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 02:02:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:02:45 INFO - 'USER': 'cltbld', 02:02:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:02:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:02:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:02:45 INFO - 'XPC_FLAGS': '0x0', 02:02:45 INFO - 'XPC_SERVICE_NAME': '0', 02:02:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:02:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:02:45 INFO - Downloading/unpacking blobuploader==1.2.4 02:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:02:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:02:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:02:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:02:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:02:47 INFO - Downloading blobuploader-1.2.4.tar.gz 02:02:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:02:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:02:47 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 02:02:47 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 02:02:47 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 02:02:47 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 02:02:47 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 02:02:47 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 02:02:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:02:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:02:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:02:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:02:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:02:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:02:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:02:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:02:49 INFO - Downloading docopt-0.6.1.tar.gz 02:02:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:02:49 INFO - Installing collected packages: blobuploader, requests, docopt 02:02:49 INFO - Running setup.py install for blobuploader 02:02:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:02:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:02:49 INFO - Running setup.py install for requests 02:02:49 INFO - Running setup.py install for docopt 02:02:49 INFO - Successfully installed blobuploader requests docopt 02:02:49 INFO - Cleaning up... 02:02:50 INFO - Return code: 0 02:02:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:02:50 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')]} 02:02:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:02:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:50 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')]} 02:02:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:02:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:50 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 0x10af97a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af9d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda88ce7f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3bd30>, '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 0x10ac5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae32b20>, '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.LNK7LvzO9c/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.RtWn47hrxU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 02:02:50 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 02:02:50 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 02:02:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNK7LvzO9c/Render', 02:02:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:02:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:02:50 INFO - 'HOME': '/Users/cltbld', 02:02:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:02:50 INFO - 'LOGNAME': 'cltbld', 02:02:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:02:50 INFO - 'MOZ_NO_REMOTE': '1', 02:02:50 INFO - 'NO_EM_RESTART': '1', 02:02:50 INFO - 'PAGER': '/bin/cat', 02:02:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:02:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:02:50 INFO - 'PWD': '/builds/slave/test', 02:02:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:02:50 INFO - 'SHELL': '/bin/bash', 02:02:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RtWn47hrxU/Listeners', 02:02:50 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 02:02:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:02:50 INFO - 'USER': 'cltbld', 02:02:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:02:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:02:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:02:50 INFO - 'XPC_FLAGS': '0x0', 02:02:50 INFO - 'XPC_SERVICE_NAME': '0', 02:02:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:02:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:02:50 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-AbUsTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:02:50 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-LG6M_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:02:50 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-wIRZOr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:02:50 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-4ENzhE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:02:50 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-d2Iueu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:02:50 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-9nDmti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:02:50 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-i1rVpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:02:50 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-VSgMFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:02:51 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-W6tqOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:02:51 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-OiKU8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:02:51 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-_Pho2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:02:51 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-7D0eeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:02:51 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-hyV2cm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:02:51 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-ttCSXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:02:51 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-dA2N0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:02:51 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-pHe6bm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:02:51 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-cNmCvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:02:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:02:52 INFO - Running setup.py install for manifestparser 02:02:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:02:52 INFO - Running setup.py install for mozcrash 02:02:52 INFO - Running setup.py install for mozdebug 02:02:52 INFO - Running setup.py install for mozdevice 02:02:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:02:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:02:52 INFO - Running setup.py install for mozfile 02:02:52 INFO - Running setup.py install for mozhttpd 02:02:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:02:52 INFO - Running setup.py install for mozinfo 02:02:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:02:53 INFO - Running setup.py install for mozInstall 02:02:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:02:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:02:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:02:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:02:53 INFO - Running setup.py install for mozleak 02:02:53 INFO - Running setup.py install for mozlog 02:02:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:02:53 INFO - Running setup.py install for moznetwork 02:02:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:02:53 INFO - Running setup.py install for mozprocess 02:02:53 INFO - Running setup.py install for mozprofile 02:02:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:02:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:02:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:02:53 INFO - Running setup.py install for mozrunner 02:02:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:02:54 INFO - Running setup.py install for mozscreenshot 02:02:54 INFO - Running setup.py install for moztest 02:02:54 INFO - Running setup.py install for mozversion 02:02:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:02:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:02:54 INFO - Cleaning up... 02:02:54 INFO - Return code: 0 02:02:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:02:54 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')]} 02:02:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:02:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:54 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')]} 02:02:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:02:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:02:54 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 0x10af97a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af9d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda88ce7f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3bd30>, '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 0x10ac5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae32b20>, '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.LNK7LvzO9c/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.RtWn47hrxU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 02:02:54 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 02:02:54 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 02:02:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNK7LvzO9c/Render', 02:02:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:02:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:02:54 INFO - 'HOME': '/Users/cltbld', 02:02:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:02:54 INFO - 'LOGNAME': 'cltbld', 02:02:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:02:54 INFO - 'MOZ_NO_REMOTE': '1', 02:02:54 INFO - 'NO_EM_RESTART': '1', 02:02:54 INFO - 'PAGER': '/bin/cat', 02:02:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:02:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:02:54 INFO - 'PWD': '/builds/slave/test', 02:02:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:02:54 INFO - 'SHELL': '/bin/bash', 02:02:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RtWn47hrxU/Listeners', 02:02:54 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 02:02:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:02:54 INFO - 'USER': 'cltbld', 02:02:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:02:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:02:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:02:54 INFO - 'XPC_FLAGS': '0x0', 02:02:54 INFO - 'XPC_SERVICE_NAME': '0', 02:02:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:02:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:02:54 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-3540YU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:02:54 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)) 02:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:02:54 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-ps9VFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:02:55 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)) 02:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:02:55 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-xi0gBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:02:55 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)) 02:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:02:55 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-BKXpaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:02:55 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)) 02:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:02:55 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-Zl7Hyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:02:55 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)) 02:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:02:55 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-8YSmKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:02:55 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)) 02:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:02:55 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-EokLm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:02:55 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)) 02:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:02:55 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-8T86qb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:02:55 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)) 02:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:02:55 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-GLX4Bw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:02:55 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)) 02:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:02:55 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-cSuuBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:02:55 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)) 02:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:02:55 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-9_aKhh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:02:56 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)) 02:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:02:56 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-u4tBgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:02:56 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)) 02:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:02:56 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-17JNbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:02:56 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)) 02:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:02:56 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-9CQwcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:02:56 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)) 02:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:02:56 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-AwxVNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:02:56 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)) 02:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:02:56 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-hi0vty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:02:56 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)) 02:02:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:02:56 INFO - Running setup.py (path:/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/pip-LKR8u0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:02:56 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)) 02:02:56 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)) 02:02:56 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)) 02:02:56 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)) 02:02:56 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)) 02:02:56 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)) 02:02:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:02: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 02:02: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 02:02: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 02:02: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 02:02: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 02:02: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 02:02:59 INFO - Downloading blessings-1.6.tar.gz 02:02:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:02:59 INFO - Installing collected packages: blessings 02:02:59 INFO - Running setup.py install for blessings 02:02:59 INFO - Successfully installed blessings 02:02:59 INFO - Cleaning up... 02:02:59 INFO - Return code: 0 02:02:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:02:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:02:59 INFO - Reading from file tmpfile_stdout 02:02:59 INFO - Current package versions: 02:02:59 INFO - blessings == 1.6 02:02:59 INFO - blobuploader == 1.2.4 02:02:59 INFO - docopt == 0.6.1 02:02:59 INFO - manifestparser == 1.1 02:02:59 INFO - mozInstall == 1.12 02:02:59 INFO - mozcrash == 0.17 02:02:59 INFO - mozdebug == 0.1 02:02:59 INFO - mozdevice == 0.48 02:02:59 INFO - mozfile == 1.2 02:02:59 INFO - mozhttpd == 0.7 02:02:59 INFO - mozinfo == 0.9 02:02:59 INFO - mozleak == 0.1 02:02:59 INFO - mozlog == 3.2 02:02:59 INFO - moznetwork == 0.27 02:02:59 INFO - mozprocess == 0.22 02:02:59 INFO - mozprofile == 0.28 02:02:59 INFO - mozrunner == 6.11 02:02:59 INFO - mozscreenshot == 0.1 02:02:59 INFO - mozsystemmonitor == 0.3 02:02:59 INFO - moztest == 0.7 02:02:59 INFO - mozversion == 1.4 02:02:59 INFO - psutil == 3.1.1 02:02:59 INFO - requests == 1.2.3 02:02:59 INFO - wsgiref == 0.1.2 02:02:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03: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')]} 02:03:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03:00 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 0x10af97a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af9d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda88ce7f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3bd30>, '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 0x10ac5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae32b20>, '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.LNK7LvzO9c/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.RtWn47hrxU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 02:03:00 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 02:03:00 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 02:03:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNK7LvzO9c/Render', 02:03:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:00 INFO - 'HOME': '/Users/cltbld', 02:03:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:00 INFO - 'LOGNAME': 'cltbld', 02:03:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:00 INFO - 'MOZ_NO_REMOTE': '1', 02:03:00 INFO - 'NO_EM_RESTART': '1', 02:03:00 INFO - 'PAGER': '/bin/cat', 02:03:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:00 INFO - 'PWD': '/builds/slave/test', 02:03:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:00 INFO - 'SHELL': '/bin/bash', 02:03:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RtWn47hrxU/Listeners', 02:03:00 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 02:03:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:00 INFO - 'USER': 'cltbld', 02:03:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:00 INFO - 'XPC_FLAGS': '0x0', 02:03:00 INFO - 'XPC_SERVICE_NAME': '0', 02:03:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:00 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)) 02:03:00 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)) 02:03:00 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)) 02:03:00 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)) 02:03:00 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)) 02:03:00 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)) 02:03:00 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)) 02:03:00 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)) 02:03:00 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)) 02:03:00 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)) 02:03:00 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)) 02:03:00 INFO - Cleaning up... 02:03:00 INFO - Return code: 0 02:03:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:03: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')]} 02:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03: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')]} 02:03:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10af97a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af9d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda88ce7f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3bd30>, '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 0x10ac5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae32b20>, '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.LNK7LvzO9c/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.RtWn47hrxU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 02:03: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', 'jsonschema'] in /builds/slave/test/build 02:03: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 jsonschema 02:03:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNK7LvzO9c/Render', 02:03:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:00 INFO - 'HOME': '/Users/cltbld', 02:03:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:00 INFO - 'LOGNAME': 'cltbld', 02:03:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:00 INFO - 'MOZ_NO_REMOTE': '1', 02:03:00 INFO - 'NO_EM_RESTART': '1', 02:03:00 INFO - 'PAGER': '/bin/cat', 02:03:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:00 INFO - 'PWD': '/builds/slave/test', 02:03:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:00 INFO - 'SHELL': '/bin/bash', 02:03:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RtWn47hrxU/Listeners', 02:03:00 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 02:03:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:00 INFO - 'USER': 'cltbld', 02:03:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:00 INFO - 'XPC_FLAGS': '0x0', 02:03:00 INFO - 'XPC_SERVICE_NAME': '0', 02:03:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:00 INFO - Downloading/unpacking jsonschema 02:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:03:03 INFO - Downloading/unpacking functools32 (from jsonschema) 02:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:03:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:03:03 INFO - Downloading functools32-3.2.3-2.tar.gz 02:03:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:03:03 INFO - warning: no files found matching '*.txt' 02:03:03 INFO - no previously-included directories found matching 'build' 02:03:03 INFO - no previously-included directories found matching 'dist' 02:03:03 INFO - no previously-included directories found matching '.git*' 02:03:03 INFO - Installing collected packages: jsonschema, functools32 02:03:03 INFO - Running setup.py install for functools32 02:03:03 INFO - warning: no files found matching '*.txt' 02:03:03 INFO - no previously-included directories found matching 'build' 02:03:03 INFO - no previously-included directories found matching 'dist' 02:03:03 INFO - no previously-included directories found matching '.git*' 02:03:03 INFO - Successfully installed jsonschema functools32 02:03:03 INFO - Cleaning up... 02:03:03 INFO - Return code: 0 02:03:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:03: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')]} 02:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:03:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03: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')]} 02:03:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:03:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:03: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10af97a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af9d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda88ce7f70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3bd30>, '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 0x10ac5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae32b20>, '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.LNK7LvzO9c/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.RtWn47hrxU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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 02:03: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', 'flake8'] in /builds/slave/test/build 02:03: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 flake8 02:03:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNK7LvzO9c/Render', 02:03:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:03 INFO - 'HOME': '/Users/cltbld', 02:03:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:03 INFO - 'LOGNAME': 'cltbld', 02:03:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:03 INFO - 'MOZ_NO_REMOTE': '1', 02:03:03 INFO - 'NO_EM_RESTART': '1', 02:03:03 INFO - 'PAGER': '/bin/cat', 02:03:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:03 INFO - 'PWD': '/builds/slave/test', 02:03:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:03 INFO - 'SHELL': '/bin/bash', 02:03:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RtWn47hrxU/Listeners', 02:03:03 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 02:03:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:03 INFO - 'USER': 'cltbld', 02:03:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:03 INFO - 'XPC_FLAGS': '0x0', 02:03:03 INFO - 'XPC_SERVICE_NAME': '0', 02:03:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:03:04 INFO - Downloading/unpacking flake8 02:03: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 02:03: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 02:03: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 02:03: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 02:03: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 02:03: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 02:03:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:03:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:03: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 02:03: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 02:03: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 02:03: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 02:03: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 02:03: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 02:03:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:03:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:03: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 02:03: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 02:03: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 02:03: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 02:03: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 02:03: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 02:03:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:03: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 02:03: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 02:03: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 02:03: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 02:03: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 02:03: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 02:03:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:03:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:03:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:03:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:03:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:03:07 INFO - no previously-included directories found matching 'docs/_build' 02:03:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:03:07 INFO - Running setup.py install for pep8 02:03:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:03:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:03:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:03:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:03:08 INFO - no previously-included directories found matching 'docs/_build' 02:03:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:03:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:03:08 INFO - Cleaning up... 02:03:08 INFO - Return code: 0 02:03:08 INFO - Running post-action listener: _resource_record_post_action 02:03:08 INFO - Running post-action listener: _start_resource_monitoring 02:03:08 INFO - Starting resource monitoring. 02:03:08 INFO - ##### 02:03:08 INFO - ##### Finished create-virtualenv step (success) 02:03:08 INFO - ##### 02:03:08 INFO - ##### 02:03:08 INFO - ##### Running install step. 02:03:08 INFO - ##### 02:03:08 INFO - Running pre-action listener: _resource_record_pre_action 02:03:08 INFO - Running main action method: install 02:03:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:03:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:03:08 INFO - Reading from file tmpfile_stdout 02:03:08 INFO - Detecting whether we're running mozinstall >=1.0... 02:03:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:03:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:03:08 INFO - Reading from file tmpfile_stdout 02:03:08 INFO - Output received: 02:03:08 INFO - Usage: mozinstall [options] installer 02:03:08 INFO - Options: 02:03:08 INFO - -h, --help show this help message and exit 02:03:08 INFO - -d DEST, --destination=DEST 02:03:08 INFO - Directory to install application into. [default: 02:03:08 INFO - "/builds/slave/test"] 02:03:08 INFO - --app=APP Application being installed. [default: firefox] 02:03:08 INFO - mkdir: /builds/slave/test/build/application 02:03:08 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'] 02:03:08 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 02:03:42 INFO - Reading from file tmpfile_stdout 02:03:42 INFO - Output received: 02:03:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:03:42 INFO - Running post-action listener: _resource_record_post_action 02:03:42 INFO - ##### 02:03:42 INFO - ##### Finished install step (success) 02:03:42 INFO - ##### 02:03:42 INFO - ##### 02:03:42 INFO - ##### Running run-tests step. 02:03:42 INFO - ##### 02:03:42 INFO - Running pre-action listener: _resource_record_pre_action 02:03:42 INFO - Running main action method: run_tests 02:03:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:03:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:03:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:03:42 INFO - 2016-05-23 02:03:42.183 screenresolution[1942:62227] starting screenresolution argv=screenresolution get 02:03:42 INFO - 2016-05-23 02:03:42.186 screenresolution[1942:62227] Display 0: 1600x1200x32@60 02:03:42 INFO - 2016-05-23 02:03:42.192 screenresolution[1943:62229] starting screenresolution argv=screenresolution list 02:03:42 INFO - Available Modes on Display 0 02:03:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:03:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:03:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:03:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:03:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:03:42 INFO - Intel Iris: 02:03:42 INFO - Chipset Model: Intel Iris 02:03:42 INFO - Type: GPU 02:03:42 INFO - Bus: Built-In 02:03:42 INFO - VRAM (Dynamic, Max): 1536 MB 02:03:42 INFO - Vendor: Intel (0x8086) 02:03:42 INFO - Device ID: 0x0a2e 02:03:42 INFO - Revision ID: 0x0009 02:03:42 INFO - Displays: 02:03:42 INFO - Display: 02:03:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:03:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:03:42 INFO - Display Serial Number: 1600x1200 60 02:03:42 INFO - Main Display: Yes 02:03:42 INFO - Mirror: Off 02:03:42 INFO - Online: Yes 02:03:42 INFO - Rotation: Supported 02:03:42 INFO - Return code: 0 02:03:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:03:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:03:42 INFO - Python 2.7.3 02:03:42 INFO - Return code: 0 02:03:42 INFO - grabbing minidump binary from tooltool 02:03:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:03:42 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 0x10ae3bd30>, '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 0x10ac5fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae32b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:03:42 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 02:03:42 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 02:03:42 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 02:03:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:03:42 INFO - Return code: 0 02:03:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:03:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:03:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:03:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:03:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:03:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:03:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:03:43 INFO - Return code: 0 02:03:43 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/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0220', '--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 02:03:43 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/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0220 --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 02:03:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LNK7LvzO9c/Render', 02:03:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:03:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:03:43 INFO - 'HOME': '/Users/cltbld', 02:03:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:03:43 INFO - 'LOGNAME': 'cltbld', 02:03:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:03:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:03:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:03:43 INFO - 'MOZ_NO_REMOTE': '1', 02:03:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:03:43 INFO - 'NO_EM_RESTART': '1', 02:03:43 INFO - 'PAGER': '/bin/cat', 02:03:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:03:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:03:43 INFO - 'PWD': '/builds/slave/test', 02:03:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:03:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:03:43 INFO - 'SHELL': '/bin/bash', 02:03:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RtWn47hrxU/Listeners', 02:03:43 INFO - 'TMPDIR': '/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/', 02:03:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:03:43 INFO - 'USER': 'cltbld', 02:03:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:03:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:03:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:03:43 INFO - 'XPC_FLAGS': '0x0', 02:03:43 INFO - 'XPC_SERVICE_NAME': '0', 02:03:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:03:43 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/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0220', '--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 02:03:43 INFO - mozversion application_buildid: 20160523001034 02:03:43 INFO - mozversion application_changeset: 64ad199ede21c4ab89e4b65b5b62351a3bbe5a45 02:03:43 INFO - mozversion application_display_name: Nightly 02:03:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:03:43 INFO - mozversion application_name: Firefox 02:03:43 INFO - mozversion application_remotingname: firefox 02:03:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 02:03:43 INFO - mozversion application_vendor: Mozilla 02:03:43 INFO - mozversion application_version: 49.0a1 02:03:43 INFO - mozversion platform_buildid: 20160523001034 02:03:43 INFO - mozversion platform_changeset: 64ad199ede21c4ab89e4b65b5b62351a3bbe5a45 02:03:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 02:03:43 INFO - mozversion platform_version: 49.0a1 02:03:43 INFO - using testdate: 1463994223 02:03:43 INFO - actual date: 1463994223 02:03:43 INFO - starting webserver on 'localhost:50562' 02:03:43 INFO - SUITE-START | Running 2 tests 02:03:43 INFO - TEST-START | dromaeo_css 02:03:43 INFO - operating with platform_type : mac_ 02:03:43 INFO - Initialising browser for dromaeo_css test... 02:03:43 INFO - TEST-INFO | started process 1961 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/tmpzWAZhO/profile http://localhost:50562/getInfo.html) 02:03:46 INFO - PROCESS | 1961 | __metrics Screen width/height:1600/1200 02:03:46 INFO - PROCESS | 1961 | colorDepth:24 02:03:46 INFO - PROCESS | 1961 | Browser inner width/height: 1024/689 02:03:46 INFO - PROCESS | 1961 | __metrics 02:03:46 INFO - PROCESS | 1961 | 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" 02:03:46 ERROR - PROCESS | 1961 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:03:47 INFO - TEST-INFO | 1961: exit 0 02:03:47 INFO - Browser initialized. 02:03:47 INFO - Running cycle 1/1 for dromaeo_css test... 02:03:47 INFO - TEST-INFO | started process 1963 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/tmpzWAZhO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:03:58 INFO - PROCESS | 1963 | RSS: Main: 239005696 02:03:58 INFO - PROCESS | 1963 | 02:04:50 INFO - PROCESS | 1963 | 1463994290093 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] 02:07:00 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50562/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50562/tests/dromaeo/cssquery-ext.html) 02:07:01 INFO - PROCESS | 1963 | RSS: Main: 331354112 02:07:01 INFO - PROCESS | 1963 | 02:10:04 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50562/tests/dromaeo/cssquery-ext.html (next: http://localhost:50562/tests/dromaeo/cssquery-jquery.html) 02:10:04 INFO - PROCESS | 1963 | RSS: Main: 374800384 02:10:04 INFO - PROCESS | 1963 | 02:13:06 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50562/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50562/tests/dromaeo/cssquery-mootools.html) 02:13:07 INFO - PROCESS | 1963 | RSS: Main: 369037312 02:13:07 INFO - PROCESS | 1963 | 02:16:09 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50562/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50562/tests/dromaeo/cssquery-prototype.html) 02:16:10 INFO - PROCESS | 1963 | RSS: Main: 367710208 02:16:10 INFO - PROCESS | 1963 | 02:19:13 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50562/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50562/tests/dromaeo/cssquery-yui.html) 02:19:13 INFO - PROCESS | 1963 | RSS: Main: 334798848 02:19:13 INFO - PROCESS | 1963 | 02:22:16 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:50562/tests/dromaeo/cssquery-yui.html (next: http://localhost:50562/tests/dromaeo/cssquery-dojo.html) 02:22:17 INFO - PROCESS | 1963 | RSS: Main: 376492032 02:22:17 INFO - PROCESS | 1963 | 02:22:17 INFO - PROCESS | 1963 | __start_tp_report 02:22:17 INFO - PROCESS | 1963 | _x_x_mozilla_page_load 02:22:17 INFO - PROCESS | 1963 | _x_x_mozilla_page_load_details 02:22:17 INFO - PROCESS | 1963 | |i|pagename|runs| 02:22:17 INFO - PROCESS | 1963 | |0;dojo.html;2814.8451835149067;3420.230448149165;3447.7069449096825;3465.116395319193;3487.9476807847886;5636.802711905079;5657.830265092043;5710.914336284954;5741.856453588663;5746.626469279494;5710.914336284959;5737.225474560933;5739.225204597378;5868.442497962697;5880.147378630107;5177.197534382177;5206.921896171561;5228.529432351088;5280;5376.435474275204;5316.3354580677415;5332.626716129873;5370.624056316059;5384.1923711443205;5398.379186393576;5141.177411614161;5149.407818100929;5226.973865130688;5249.606279529051;5251.002309561171;5217.19133534299;5228.215767634847;5286;5295.735213239323;5304.947645278135;5237.633365664405;5310.230016647603;5319.867003324909;5340.145576707747;5349.7057661828585;5567.47109024642;5604.2714447122025;5663.377028526858;5667.121596152603;5709.172170035363;5804.390538993429;5931.911021334683;6244.875102497925;6320.241571011508;6338.334474880117;4982.825601103944;6159.692015399212;6233.283172435137;6280.968595157041;6295.937040629581;5799.3910639382675;5863.912041319383;5871.442212989759;5890.8821823563285;5904.084866845646;6276.686165691697;6296.779612713533;6305.873882522324;6329.019002054689;6332.841678958063;6072.301685306158;6076;6197.03945888388;6236.03341482071;6258.530610204253;6208.968955155286;6274.403931626488;6278.4035516625845;6309.179806625201;6320.652364119926;6234.189555357774;6269.7178626961895;6288.308286088527;6303;6309.053641953652;6143.262808463013;6146.7091910699255;6302;6311.2426508819235;6326.398992095744;4650.441946966318;4974.975125124423;4980.377452818398;5000.924986125211;5027.99440111979;3885.922281554353;4427.048184640254;4478.708883922534;4485.349624304457;4501.68488205829;5571.804986825442;5587.05020146576;5711.571632127607;5762.423757624204;5780.277465316835;5198.324217851655;6258.968705156536;6260.780872669526;6273.780417685451;6284.465820405317;5173.896522069613;5576.358718747396;5793.2179155814265;5827.883442331215;5828.242328497268;5352.678839269656;5430.701311427831;5475.069238229431;5479.780808767684;5487.1494918286935;6409.871802563923;6411.455026322815;6414.807555773335;6432.742690292336;6457.61254324742;6262.53031022666;6309.211348581427;6311.0217916223055;6314.336994615683;6321.557490975588;6187.535934804998;6238.469730072813;6259.3427690091885;6297.716602752796;6419.807405777834;5731.356384437635;6200.348963358782;6233.283172435046;6247.906281405873;6257.812265632038;6241.750329986749;6305.306416294112;6306;6311.179546658905;6322.36776322364;5868.794592189253;6080.268688454573;6097.857415703088;6125.632272693224;6172.598781079395;6184.5980011299125;6199.349068347939;6205.844853878689;6211.596246243979;6214.223222097238;5874.500667443144;6065.302490213682;6085.786997455068;6086.786962456293;6091.634501929899;6105.412836282587;6108.448827822033;6119.112728654409;6218.906716399348;6224.128621992834;6016.819495415145;6057.394260573908;6069.02895536712;6079.422454866781;6093.129467831542 02:22:17 INFO - PROCESS | 1963 | |1;ext.html;505.8128492457791;506.52386756448936;507.7892674540066;509.3378607809846;557.9051561234593;42077.57922420775;43495.56504434955;43552;43741;45856.54143458564;43903.78048109755;44417.55582444175;44454.33318500225;44458.555414445844;44546.33180502295;9609.135177833976;9826.656067037655;9828.754281142958;9830.852537211911;9835.065668761492;9536.809263814719;9628.229741620653;9695.321327507078;9717.659881904136;9722.319437639368;9643.710688679352;9733.56198971048;9774.853377199348;9784.95107524465;9814.558344874498;9714.951425242863;9717.708468745914;9757.316987810857;9757.609695612167;9759.219262459057;9493.0981556752;9552.283578731623;9607.663731769355;9612.038796120332;9642.614295428175;40939.59060409388;41435.171296574204;41484.58515414837;41490;41541.96145096367;3072.201227680797;3166.0976621662717;3169;3569.625189355132;3846.3845784674318;29281.6229944011;29339.82640694391;30024.69975300241;30524.54213186804;30670.386592268253;2709.078913169532;2727.9318017049536;2730.331068888135;2835.773138148964;2886.9133925982255;506151.99999999633;513112;513269.4336528293;513790;515408;61636;62149.68925155346;62553.374466254754;62569.68715156395;62729;56551.434485654616;57975;64751.676241618494;66522.66738666275;66557.66721166363;16576.502704918872;16769.580760480843;16786.160691965353;16786.916065419595;16827.579310517336;11689.532418703157;12890.29103399322;12990.610281691375;12995.610131696065;12999.1550549214;16950.49148525546;17348.3060677574;17356.305747769948;17456;17521.474355769347;5857.589968702235;6558.80323590293;6610.636414997125;6638.83402914931;6656.30108838565;914.8810654614857;1014.9695509134738;1054.6361505280665;1077.4828082520435;1109.3842917181041;28702.85648571744;29512;29548.409031819243;29935.401291974038;30204.395912082076;1072.1476426241156;1104.9226554141294;1146.853840621057;1148.9827652585218;1171.6485054483621;14428.711425771426;15206.847931520764;15285.770713439308;15586.454474093352;15604.687906241812;627492;637560;639034;640789;643636;60408;60565.394346056855;60590.39409605935;60649.69675151684;60765;1303.9348032598523;1349.5479014530242;1429.8641629045223;1442.2500299844023;1475.4835807467516;24540.772961351504;30042;30141.095767127023;30708.385832283675;32620.673793262715;52851.207231892324;53126.4687353137;53404;53427;53728;546398;550746;551774;555388.2230588822;564593;39341.6065839338;39953.601623943025;40813.59186408217;40951.59048409478;41029.38455923104;45627.859303517675;46607.30089048599;46678.53321466878;46821.765891170326;48152.759236203594;38481.8455446337;38842.417363739;39163.02092447712;39910.00224994282;40277.99305017398;35773.284534309685;37819.62180378161;37969.62030379661;38941.66350019008;39027.41458878062;35576.46635300421;36894.446583300734;37885.242295154494;38619.613803861605;40241.59758402379;30988;31496.527552087195;33709.15727106842;34316.14209644679;34619.30761384808;24640.50718985646;25021.374465638506;25220;25439.982400703764;25511.6173257405 02:22:17 INFO - PROCESS | 1963 | |2;jquery.html;376.9641884021018;382.35763916620084;383.9174577465845;394.96247856453624;399.16773527195795;19196.808031919678;19999.800001999974;20374;20720.792792072094;20723.378298651027;20097.397078087644;20649.174033038697;20685.069171887262;21089.894550527264;21310.54928683198;5738.651416917021;5898.970505147479;6107.32819389869;6118.63288202707;6133.2026836511195;5869.471747542721;5972.761089556412;6018.638881667114;6078.939210607881;6089.147519347295;5808.070708686611;5868.618539794899;5971.4327138921735;5981.760729570812;5991.161237426763;5919.911201331984;5965.135055416962;5969.88060238795;5974.462298393166;6007.789727359544;5102.6173037022;5168.612354073422;5203.713795741232;5207.375114986188;5213.009528189632;20404;24329;24600.876995614908;25036;25137.371565710822;2109.841761867866;2136.7756385579446;2242.461809165805;2255.289583781104;2260.9095636174534;14471.78292325616;14571.489997850025;15003.699926001418;15162.620934476616;15564.61088472786;1688.1137402863471;1744.8953062816272;1782.1801971093312;1788.9105544722843;1928.2672584417971;90176.54911725399;97963.73305609975;132137.33931330283;133722.33138834243;140978;20326.00382887052;20408.591828163353;20779.68830467544;20907.47731306699;20935.812837432575;34766.65233347685;35625.82187089048;35716.107097322776;35783.82108089443;35869.82065089658;10360.378377297386;10813.459327033617;10988.835167472495;11019.283746556448;11167.218294719454;8933.46399216049;8939.776505587282;8977.685780997634;8993.23557497607;9061.637534498546;9669.226461883032;9716.514174291256;9745.317827752131;9795.951020244996;9867.950660246654;3238.9676103239135;3241.967580324214;3688.5102555774743;3731.9253614928093;3785.716071294664;886.6586364249811;941.665708673411;946.3186505715873;959.5058544849409;968.864358989742;19024.858508489535;19708.817470951788;19868;19919.900400497903;20081.899590501955;910.5128756115579;924.6209054287709;963.8698775665196;977.2621670638622;983.3460748602242;10383.37699738003;10589.15286777056;10601.946990265;10612.681619551426;10619.469026548642;561789;582585;583870.0806495941;608648;610876;121897.39051304686;125103.374483127;127038;130455;135832.3208383952;887.9156480134377;936.2556767369821;974.541965276319;996.4220751963844;1013.3463915774284;17414.564635884206;17629.382971595933;17716.91141544284;17731.11344432799;17884.374046908295;42415.7879210602;45008.5245311511;52964;53785.46214537804;54473.72763136159;69455;73660;73878.63060684662;75062.49875002578;83451;6775.593464392152;7089.574625522485;7177.497575169688;7253.528520646141;7349.853002940018;7570.9242907572425;9000.594973226089;9874.753131171778;10056.64801731936;10317.432541210306;7327.303906128909;8604.78488037804;9035.277177825626;9035.864462032943;9284.071592840932;8625.309975201842;8886.333524985523;8923.955380223057;9037.231835294073;9159.77100572491;8749;9617;10137.189024877845;10231;10563.683089507327;7609.162992070757;7651.808704782425;7705.30652241301;7744.225577442211;7749.380049596131;7036.648167591702;10233.539490723084;10263.948680256552;10316.381017138996;10464.476776161313 02:22:17 INFO - PROCESS | 1963 | |3;mootools.html;1387.3271463340284;1577.5188567486912;1586.4209563509319;1852.7869295031066;1999.1203870297068;7838.451308408414;8647.43791653543;8822.117788221183;8844;8997.325200609965;8511.404201705884;8807.95596022019;8820.338474614413;8829.426087304337;8830.116988301166;1988.2245924089543;2333.579955607994;2351.2240960483;2357.281029286067;2362.173239366217;2311.849729767568;2345.95308093838;2350.8236882233814;2352.164981431593;2352.9882350588223;2308.68832707584;2315.2822624986306;2339.4502291961376;2347.7769611886843;2357.363511851799;2290.816734661223;2295.9755653989414;2308.4459729664845;2324.569954558412;2335.708036495438;2220.134147682389;2229.4092065602626;2230.174835310945;2230.565039817236;2232.9330120096424;8580.82838343236;8709.95645021777;8733.432326898732;8773.868391974125;8787.692430764957;4788.802799300175;4898.436679781799;5117.283580298761;5250.6324557281005;5255.737213139328;3265.0694552052787;3368.9449435844117;3380.6619338066243;3389.627141014511;3466.0988143082727;1004.1565085328359;1022.437659287389;1023.5649848814298;1027.106417416852;1028.6759670703705;11580.652580422517;11665.708357291136;11694;11715.648530544097;11722;4414.337849322627;4423.778811059434;4456.376107344974;4480.14877173336;4550.681452298308;8995.190432860943;9023.639054437881;9102.81794364109;9129;9147.176754092163;5074.898502029939;5141.691498510101;5145.305383773143;5145.819896303611;5149.716765577929;4347.130573885235;4472.9776351118035;4668;4766.904661906742;4799.232122860326;3749.2314075614436;3780.7920564368674;3808.6762625176616;3850.4801851749653;3898.4542164097047;1161.529580519874;1169.3217933598492;1169.8947094761513;1173.6537721372215;1186.5965571705663;643.8165122940034;798.9121196668361;878.6221924572495;924.0251534631029;932.6988850553576;4605.976970115128;5076.756194732314;5598.664080155204;5714.914276285859;5845.240118784616;464.46586425610144;465.42984843567035;477.84708893153856;481.4319103457953;620.8509957610141;3740.8690695825517;3842.5965273646425;3933.645971862546;3938.6849052075763;3951.5258169019326;10467.214958877963;10760.031597156294;10802.351858888334;10896.509657065451;10945.398003109905;10643.361398316125;10827.83758243627;10853.945730271456;10854;10874.72813179661;7918.089419716691;8219.1369906159;8282.71010514617;8286.875696864552;8288;5598.468145526169;5661.490465858093;5709.68596727184;5714.942850571608;5731.627444216113;9680.177184939359;10076.395416275047;10142.391456512632;10148;10154.949225253826;9573.18627916635;10002.399856008662;10048.995100490185;10244.436555989492;10254.487275636338;7018.508704390848;7200.279972002758;7259.382952449101;7295.066807296177;7436.96281518589;8314.043884953307;8322.833543329221;8406.621702023302;8413.579321033802;8434.198751118634;6239.532035097295;7325.194228634739;7421.628918554159;7429.294217049372;7571.318581327487;7290.708371665074;7334.266573342623;7411.814704632212;7450.478466507292;7500.749775067349;6919.308069193041;6948.722051117898;6956.617386043817;7004.194517630334;7020.473464490286;3716.4053751399647;4695.953040469688;4837.854864354075;4882.194437917846;4960.9829984852295;4349.217140914539;4846.757662116951;4924.975375123101;4964.925526117039;5011.09800235961 02:22:17 INFO - PROCESS | 1963 | |4;prototype.html;257.8427159432746;293.8002158532198;305.71873876034056;309.9612548431446;310.61173533083644;4740.810367585301;5110.310108135401;5231.398389185245;5267.97091672288;5294.444083371249;4623.953760462395;5159.432462429129;5245.947540524594;5259.263703081571;5263.6841789492655;3978.264021156095;3983.960160398388;3998.9800050999706;4001.779902105383;4043.2722110020186;3534.692163899361;3860.7297489175767;3869.7678139311734;3988.6809055275507;4001.539822920358;3327.5341452196712;3519.9296014079705;3876.9532226298884;3955.564887862333;4043.2317859606583;3948.019245469652;3965.1871366369824;3975.6819454443576;3996.1208534122447;4002.6597739192202;3350.8324583770714;3497.545319108525;3499.0377646147513;3506.6668666476644;3531.2937412517344;4306.35404689296;4537.727736335831;4726.787294571718;4959.20652695567;5034.345535080416;4498.797554110073;4553.658475614342;4557.270836666117;4563.817447302104;4583.593613352983;4518.390017347649;5472.644278121909;5500.477454641803;5536.446355364472;5548.55611551075;3459.775114617542;3795.905102372435;3802.676772474343;3805.6384643459105;3829.9808500957597;5805.82582522525;5812.564057695689;5836.6789826559525;5847.8538036549;5856.707164641751;3816.6260146347295;3826.674732647756;3840.5967373425947;3849.826757795906;3851.7496362736333;3361.781484203519;3762.7177961652988;3911.4745249352777;3922.470466486988;3933.6066393361007;3518.5249991250853;4085.530164031115;4277.636400905958;4453.710508816916;4478.894060770724;5032.72320022395;5515.001623822776;5546.639468434539;5594.9720251398485;5621.213030175779;1170.9004734597484;1267.512007876974;1270.841144856893;1316.4207748590588;1320.3530270167741;729.5440349781387;774.9651265693058;787.46452412359;800.7828101286012;810.3355248696131;3814.5422549294262;3978.522577290744;3990.082281075369;4186.990244682569;4400.603945644843;4172.749635021848;4321.632661223831;4526.660500462479;4592.931106033412;4783.976080119577;3677.3932301170544;3736.4208547675153;4049.4128351388886;4284.742915425085;4309.525952145262;2400.3596044351075;2404.8156059089742;2524.255344673326;2544.974550254474;2564.5127425789415;4224.2331273016225;4886.33694636424;4899.951000489949;4910.930668332292;4917;4159.708820382604;4372.890677733082;4573.719358579603;4686.8125274988615;4882.926756098661;4335.414719012893;4344.652427805768;4386.627136693289;4798.008826337101;4888.902221955541;4590.74750888691;4623.352730617784;4752.6197904168275;4792.472827988988;4847.927281090857;3985.900352491211;4023.7384570002855;4325.913481730285;4680.251159814414;4714.346381554185;3990.680745540292;4380.986771157126;4437.312216606302;4812.759362031815;4935.790731270861;2865.9426811464073;3005.5341422079614;3060.5515587529444;3208.855601497985;3267.493538501536;4087.7956102194175;4273.87178384649;4506.346579745984;4545.272756358967;4739.502352252964;4821.445533763662;5009.670503216022;5430.294061771944;5549.500544950813;5582.665040097607;5050.621203409685;5271.420143784258;5530.170474428707;5532.197831314517;5546.389897111395;4869.975650121727;5059.848204553869;5081.586246532867;5082.695038297714;5427.561696150445;4719.59883409914;4820.421549414093;5575.132330669271;5586.86032849182;5624.381318054929;5336.2529245904825;5436.151708419156;5499.725013749377;5517.834464966058;5553.72231388437 02:22:17 INFO - PROCESS | 1963 | |5;yui.html;647.4334956912701;727.9199288078314;743.1453828097689;744.8026273037642;759.9886001709979;15097.396104155834;15380.154091524962;16938.999999999985;17728.734068988942;17758.46724598264;16605;16633.750493742602;16663.91668041658;16704.99770013797;16766.07786571738;725.0972539188691;751.4927423988786;928.1924725488834;942.4580866002042;944.707140786355;904.7421484876811;941.5715849288591;941.6186444489992;943.3726571829725;944.4239014201331;982.2829334585757;993.0466751918167;994.5077186792548;994.8706668133133;998.3161534348994;771.2595907928395;930.3719989007427;930.972070837876;962.6630679262272;998.211412983742;713.1371041040347;719.6617589732817;720.6000669628354;733.5342057793287;734.4454936522944;15268;15361.15513646749;15379.077255364602;15385.153816540085;15403.537893863202;10588.94705526475;12906;12973.481060757558;13093.41079651418;13113.475460981645;9640;9677.564509597085;9691.34879082523;9706;10494.842577361345;4123.2165888481095;4134.958650413518;4295.6778241632;4428.822847086113;4432.578905004019;80270;80712.19287807106;81763;81970;82703.58648206781;9334.299927505464;9585.993470685617;9768.706938791847;9956.850647240297;10356.844647330296;17575.29698812033;21610.45973850635;21694.566108677736;21729.348119556438;21736.239231627133;7325.890111648329;7409.592472414069;7465.29079737424;7474.551526908294;7510.84978300431;3919.470871432377;3972.2651309507833;3979;3980.3233450313514;3987.282289187916;2738.7261273872455;3227.967720322814;3236;3299.9010029699148;3315.867365305409;553.6816330609915;576.4840467781396;577.1371799160282;577.3014652270715;584.748558120004;452.76682508508145;539.3339226055818;560.778492495462;600.9278886533644;613.3345320327495;7989;8115.918840811634;8316.6673333066;8332.919205271646;8385.454945428528;493.4448745161693;494.77240469384486;580.1877371679682;600;607.4593611685603;3803.581606023335;3860.82626281818;3866.7486613370043;3902.921941561153;4016.4117866467736;76698.2330176691;79592;80650.5967470159;81413.59293203497;84895.72656410158;47164;72843;75420.62289688626;77093.22906770973;77848.61075694585;63849.680751595944;64462.6776866122;64998.350016500175;65129.697406051615;67328.32671673318;7589;7593.164751877179;7824.726134585262;7867.449278550446;7891.447598668267;42993.78503107464;43014.56985430106;43029.784851076794;43194.568054319054;43253;57496.52545169101;58930;59801.70099149476;60195.39804602073;60239.698801505714;10396.948015259875;10424.270301079156;10511.159107271553;10783.352998820095;11380.374079425712;10856.45717714127;10863.67408977732;11030.117590592572;11398.974092331398;11447.599334023269;10510;10627.840582391425;10778.676639700821;10791.56833726642;10796.406197659204;10343;10365.74085647865;10441.164706823587;10455.529501172618;10738.248322617343;10073.848892266475;10135.695929122137;10611.469426528796;10662.786744265226;10673.733156671145;7425.665845036879;8753.562321884008;8959.328050396376;9059.728208153765;9154.359194856295;7893.644785984759;8872.86690699627;8879.467231966102;8922.241609463268;9062.320325975446 02:22:17 INFO - PROCESS | 1963 | __end_tp_report 02:22:17 INFO - PROCESS | 1963 | __start_cc_report 02:22:17 INFO - PROCESS | 1963 | _x_x_mozilla_cycle_collect,1251 02:22:17 INFO - PROCESS | 1963 | __end_cc_report 02:22:17 INFO - PROCESS | 1963 | __startTimestamp1463995337084__endTimestamp 02:22:17 INFO - PROCESS | 1963 | 02:22:17 INFO - PROCESS | 1963 | ------- Summary: start ------- 02:22:17 INFO - PROCESS | 1963 | Number of tests: 6 02:22:17 INFO - PROCESS | 1963 | 02:22:17 INFO - PROCESS | 1963 | [#0] dojo.html Cycles:165 Average:5763.48 Median:6016.82 stddev:660.04 (11.0%) stddev-sans-first:620.21 02:22:17 INFO - PROCESS | 1963 | Values: 2814.8 3420.2 3447.7 3465.1 3487.9 5636.8 5657.8 5710.9 5741.9 5746.6 5710.9 5737.2 5739.2 5868.4 5880.1 5177.2 5206.9 5228.5 5280.0 5376.4 5316.3 5332.6 5370.6 5384.2 5398.4 5141.2 5149.4 5227.0 5249.6 5251.0 5217.2 5228.2 5286.0 5295.7 5304.9 5237.6 5310.2 5319.9 5340.1 5349.7 5567.5 5604.3 5663.4 5667.1 5709.2 5804.4 5931.9 6244.9 6320.2 6338.3 4982.8 6159.7 6233.3 6281.0 6295.9 5799.4 5863.9 5871.4 5890.9 5904.1 6276.7 6296.8 6305.9 6329.0 6332.8 6072.3 6076.0 6197.0 6236.0 6258.5 6209.0 6274.4 6278.4 6309.2 6320.7 6234.2 6269.7 6288.3 6303.0 6309.1 6143.3 6146.7 6302.0 6311.2 6326.4 4650.4 4975.0 4980.4 5000.9 5028.0 3885.9 4427.0 4478.7 4485.3 4501.7 5571.8 5587.1 5711.6 5762.4 5780.3 5198.3 6259.0 6260.8 6273.8 6284.5 5173.9 5576.4 5793.2 5827.9 5828.2 5352.7 5430.7 5475.1 5479.8 5487.1 6409.9 6411.5 6414.8 6432.7 6457.6 6262.5 6309.2 6311.0 6314.3 6321.6 6187.5 6238.5 6259.3 6297.7 6419.8 5731.4 6200.3 6233.3 6247.9 6257.8 6241.8 6305.3 6306.0 6311.2 6322.4 5868.8 6080.3 6097.9 6125.6 6172.6 6184.6 6199.3 6205.8 6211.6 6214.2 5874.5 6065.3 6085.8 6086.8 6091.6 6105.4 6108.4 6119.1 6218.9 6224.1 6016.8 6057.4 6069.0 6079.4 6093.1 02:22:17 INFO - PROCESS | 1963 | 02:22:17 INFO - PROCESS | 1963 | [#1] ext.html Cycles:180 Average:70801.68 Median:41459.88 stddev:152218.46 (367.1%) stddev-sans-first:152553.98 02:22:17 INFO - PROCESS | 1963 | Values: 505.8 506.5 507.8 509.3 557.9 42077.6 43495.6 43552.0 43741.0 45856.5 43903.8 44417.6 44454.3 44458.6 44546.3 9609.1 9826.7 9828.8 9830.9 9835.1 9536.8 9628.2 9695.3 9717.7 9722.3 9643.7 9733.6 9774.9 9785.0 9814.6 9715.0 9717.7 9757.3 9757.6 9759.2 9493.1 9552.3 9607.7 9612.0 9642.6 40939.6 41435.2 41484.6 41490.0 41542.0 3072.2 3166.1 3169.0 3569.6 3846.4 29281.6 29339.8 30024.7 30524.5 30670.4 2709.1 2727.9 2730.3 2835.8 2886.9 506152.0 513112.0 513269.4 513790.0 515408.0 61636.0 62149.7 62553.4 62569.7 62729.0 56551.4 57975.0 64751.7 66522.7 66557.7 16576.5 16769.6 16786.2 16786.9 16827.6 11689.5 12890.3 12990.6 12995.6 12999.2 16950.5 17348.3 17356.3 17456.0 17521.5 5857.6 6558.8 6610.6 6638.8 6656.3 914.9 1015.0 1054.6 1077.5 1109.4 28702.9 29512.0 29548.4 29935.4 30204.4 1072.1 1104.9 1146.9 1149.0 1171.6 14428.7 15206.8 15285.8 15586.5 15604.7 627492.0 637560.0 639034.0 640789.0 643636.0 60408.0 60565.4 60590.4 60649.7 60765.0 1303.9 1349.5 1429.9 1442.3 1475.5 24540.8 30042.0 30141.1 30708.4 32620.7 52851.2 53126.5 53404.0 53427.0 53728.0 546398.0 550746.0 551774.0 555388.2 564593.0 39341.6 39953.6 40813.6 40951.6 41029.4 45627.9 46607.3 46678.5 46821.8 48152.8 38481.8 38842.4 39163.0 39910.0 40278.0 35773.3 37819.6 37969.6 38941.7 39027.4 35576.5 36894.4 37885.2 38619.6 40241.6 30988.0 31496.5 33709.2 34316.1 34619.3 24640.5 25021.4 25220.0 25440.0 25511.6 02:22:17 INFO - PROCESS | 1963 | 02:22:17 INFO - PROCESS | 1963 | [#2] jquery.html Cycles:180 Average:35449.90 Median:5135.61 stddev:98453.83 (1917.1%) stddev-sans-first:98694.80 02:22:17 INFO - PROCESS | 1963 | Values: 377.0 382.4 383.9 395.0 399.2 19196.8 19999.8 20374.0 20720.8 20723.4 20097.4 20649.2 20685.1 21089.9 21310.5 5738.7 5899.0 6107.3 6118.6 6133.2 5869.5 5972.8 6018.6 6078.9 6089.1 5808.1 5868.6 5971.4 5981.8 5991.2 5919.9 5965.1 5969.9 5974.5 6007.8 5102.6 5168.6 5203.7 5207.4 5213.0 20404.0 24329.0 24600.9 25036.0 25137.4 2109.8 2136.8 2242.5 2255.3 2260.9 14471.8 14571.5 15003.7 15162.6 15564.6 1688.1 1744.9 1782.2 1788.9 1928.3 90176.5 97963.7 132137.3 133722.3 140978.0 20326.0 20408.6 20779.7 20907.5 20935.8 34766.7 35625.8 35716.1 35783.8 35869.8 10360.4 10813.5 10988.8 11019.3 11167.2 8933.5 8939.8 8977.7 8993.2 9061.6 9669.2 9716.5 9745.3 9796.0 9868.0 3239.0 3242.0 3688.5 3731.9 3785.7 886.7 941.7 946.3 959.5 968.9 19024.9 19708.8 19868.0 19919.9 20081.9 910.5 924.6 963.9 977.3 983.3 10383.4 10589.2 10601.9 10612.7 10619.5 561789.0 582585.0 583870.1 608648.0 610876.0 121897.4 125103.4 127038.0 130455.0 135832.3 887.9 936.3 974.5 996.4 1013.3 17414.6 17629.4 17716.9 17731.1 17884.4 42415.8 45008.5 52964.0 53785.5 54473.7 69455.0 73660.0 73878.6 75062.5 83451.0 6775.6 7089.6 7177.5 7253.5 7349.9 7570.9 9000.6 9874.8 10056.6 10317.4 7327.3 8604.8 9035.3 9035.9 9284.1 8625.3 8886.3 8924.0 9037.2 9159.8 8749.0 9617.0 10137.2 10231.0 10563.7 7609.2 7651.8 7705.3 7744.2 7749.4 7036.6 10233.5 10263.9 10316.4 10464.5 02:22:17 INFO - PROCESS | 1963 | 02:22:17 INFO - PROCESS | 1963 | [#3] mootools.html Cycles:180 Average:5595.42 Median:4681.98 stddev:3222.74 (68.8%) stddev-sans-first:3216.26 02:22:17 INFO - PROCESS | 1963 | Values: 1387.3 1577.5 1586.4 1852.8 1999.1 7838.5 8647.4 8822.1 8844.0 8997.3 8511.4 8808.0 8820.3 8829.4 8830.1 1988.2 2333.6 2351.2 2357.3 2362.2 2311.8 2346.0 2350.8 2352.2 2353.0 2308.7 2315.3 2339.5 2347.8 2357.4 2290.8 2296.0 2308.4 2324.6 2335.7 2220.1 2229.4 2230.2 2230.6 2232.9 8580.8 8710.0 8733.4 8773.9 8787.7 4788.8 4898.4 5117.3 5250.6 5255.7 3265.1 3368.9 3380.7 3389.6 3466.1 1004.2 1022.4 1023.6 1027.1 1028.7 11580.7 11665.7 11694.0 11715.6 11722.0 4414.3 4423.8 4456.4 4480.1 4550.7 8995.2 9023.6 9102.8 9129.0 9147.2 5074.9 5141.7 5145.3 5145.8 5149.7 4347.1 4473.0 4668.0 4766.9 4799.2 3749.2 3780.8 3808.7 3850.5 3898.5 1161.5 1169.3 1169.9 1173.7 1186.6 643.8 798.9 878.6 924.0 932.7 4606.0 5076.8 5598.7 5714.9 5845.2 464.5 465.4 477.8 481.4 620.9 3740.9 3842.6 3933.6 3938.7 3951.5 10467.2 10760.0 10802.4 10896.5 10945.4 10643.4 10827.8 10853.9 10854.0 10874.7 7918.1 8219.1 8282.7 8286.9 8288.0 5598.5 5661.5 5709.7 5714.9 5731.6 9680.2 10076.4 10142.4 10148.0 10154.9 9573.2 10002.4 10049.0 10244.4 10254.5 7018.5 7200.3 7259.4 7295.1 7437.0 8314.0 8322.8 8406.6 8413.6 8434.2 6239.5 7325.2 7421.6 7429.3 7571.3 7290.7 7334.3 7411.8 7450.5 7500.7 6919.3 6948.7 6956.6 7004.2 7020.5 3716.4 4696.0 4837.9 4882.2 4961.0 4349.2 4846.8 4925.0 4964.9 5011.1 02:22:17 INFO - PROCESS | 1963 | 02:22:17 INFO - PROCESS | 1963 | [#4] prototype.html Cycles:180 Average:4153.56 Median:4466.30 stddev:1268.50 (28.4%) stddev-sans-first:1237.90 02:22:17 INFO - PROCESS | 1963 | Values: 257.8 293.8 305.7 310.0 310.6 4740.8 5110.3 5231.4 5268.0 5294.4 4624.0 5159.4 5245.9 5259.3 5263.7 3978.3 3984.0 3999.0 4001.8 4043.3 3534.7 3860.7 3869.8 3988.7 4001.5 3327.5 3519.9 3877.0 3955.6 4043.2 3948.0 3965.2 3975.7 3996.1 4002.7 3350.8 3497.5 3499.0 3506.7 3531.3 4306.4 4537.7 4726.8 4959.2 5034.3 4498.8 4553.7 4557.3 4563.8 4583.6 4518.4 5472.6 5500.5 5536.4 5548.6 3459.8 3795.9 3802.7 3805.6 3830.0 5805.8 5812.6 5836.7 5847.9 5856.7 3816.6 3826.7 3840.6 3849.8 3851.7 3361.8 3762.7 3911.5 3922.5 3933.6 3518.5 4085.5 4277.6 4453.7 4478.9 5032.7 5515.0 5546.6 5595.0 5621.2 1170.9 1267.5 1270.8 1316.4 1320.4 729.5 775.0 787.5 800.8 810.3 3814.5 3978.5 3990.1 4187.0 4400.6 4172.7 4321.6 4526.7 4592.9 4784.0 3677.4 3736.4 4049.4 4284.7 4309.5 2400.4 2404.8 2524.3 2545.0 2564.5 4224.2 4886.3 4900.0 4910.9 4917.0 4159.7 4372.9 4573.7 4686.8 4882.9 4335.4 4344.7 4386.6 4798.0 4888.9 4590.7 4623.4 4752.6 4792.5 4847.9 3985.9 4023.7 4325.9 4680.3 4714.3 3990.7 4381.0 4437.3 4812.8 4935.8 2865.9 3005.5 3060.6 3208.9 3267.5 4087.8 4273.9 4506.3 4545.3 4739.5 4821.4 5009.7 5430.3 5549.5 5582.7 5050.6 5271.4 5530.2 5532.2 5546.4 4870.0 5059.8 5081.6 5082.7 5427.6 4719.6 4820.4 5575.1 5586.9 5624.4 5336.3 5436.2 5499.7 5517.8 5553.7 02:22:17 INFO - PROCESS | 1963 | 02:22:17 INFO - PROCESS | 1963 | [#5] yui.html Cycles:180 Average:17161.90 Median:59365.85 stddev:23499.25 (39.6%) stddev-sans-first:23532.46 02:22:17 INFO - PROCESS | 1963 | Values: 647.4 727.9 743.1 744.8 760.0 15097.4 15380.2 16939.0 17728.7 17758.5 16605.0 16633.8 16663.9 16705.0 16766.1 725.1 751.5 928.2 942.5 944.7 904.7 941.6 941.6 943.4 944.4 982.3 993.0 994.5 994.9 998.3 771.3 930.4 931.0 962.7 998.2 713.1 719.7 720.6 733.5 734.4 15268.0 15361.2 15379.1 15385.2 15403.5 10588.9 12906.0 12973.5 13093.4 13113.5 9640.0 9677.6 9691.3 9706.0 10494.8 4123.2 4135.0 4295.7 4428.8 4432.6 80270.0 80712.2 81763.0 81970.0 82703.6 9334.3 9586.0 9768.7 9956.9 10356.8 17575.3 21610.5 21694.6 21729.3 21736.2 7325.9 7409.6 7465.3 7474.6 7510.8 3919.5 3972.3 3979.0 3980.3 3987.3 2738.7 3228.0 3236.0 3299.9 3315.9 553.7 576.5 577.1 577.3 584.7 452.8 539.3 560.8 600.9 613.3 7989.0 8115.9 8316.7 8332.9 8385.5 493.4 494.8 580.2 600.0 607.5 3803.6 3860.8 3866.7 3902.9 4016.4 76698.2 79592.0 80650.6 81413.6 84895.7 47164.0 72843.0 75420.6 77093.2 77848.6 63849.7 64462.7 64998.4 65129.7 67328.3 7589.0 7593.2 7824.7 7867.4 7891.4 42993.8 43014.6 43029.8 43194.6 43253.0 57496.5 58930.0 59801.7 60195.4 60239.7 10396.9 10424.3 10511.2 10783.4 11380.4 10856.5 10863.7 11030.1 11399.0 11447.6 10510.0 10627.8 10778.7 10791.6 10796.4 10343.0 10365.7 10441.2 10455.5 10738.2 10073.8 10135.7 10611.5 10662.8 10673.7 7425.7 8753.6 8959.3 9059.7 9154.4 7893.6 8872.9 8879.5 8922.2 9062.3 02:22:17 INFO - PROCESS | 1963 | -------- Summary: end -------- 02:22:17 INFO - PROCESS | 1963 | 02:22:17 INFO - TEST-INFO | 1963: exit 0 02:22:17 INFO - TEST-OK | dromaeo_css | took 1113785ms 02:22:17 INFO - TEST-START | kraken 02:22:17 INFO - operating with platform_type : mac_ 02:22:17 INFO - Initialising browser for kraken test... 02:22:17 INFO - TEST-INFO | started process 1965 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/tmpGmFAaO/profile http://localhost:50562/getInfo.html) 02:22:19 INFO - PROCESS | 1965 | __metrics Screen width/height:1600/1200 02:22:19 INFO - PROCESS | 1965 | colorDepth:24 02:22:19 INFO - PROCESS | 1965 | Browser inner width/height: 1024/689 02:22:19 INFO - PROCESS | 1965 | __metrics 02:22:20 INFO - PROCESS | 1965 | 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" 02:22:20 ERROR - PROCESS | 1965 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:22:20 INFO - TEST-INFO | 1965: exit 0 02:22:20 INFO - Browser initialized. 02:22:20 INFO - Running cycle 1/1 for kraken test... 02:22:20 INFO - TEST-INFO | started process 1967 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/T/tmpGmFAaO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:22:32 INFO - PROCESS | 1967 | RSS: Main: 238739456 02:22:32 INFO - PROCESS | 1967 | 02:22:35 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50562/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50562/tests/kraken/driver.html?testName=audio-beat-detection) 02:22:36 INFO - PROCESS | 1967 | RSS: Main: 287633408 02:22:36 INFO - PROCESS | 1967 | 02:22:39 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50562/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50562/tests/kraken/driver.html?testName=audio-dft) 02:22:39 INFO - PROCESS | 1967 | RSS: Main: 296062976 02:22:39 INFO - PROCESS | 1967 | 02:22:43 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50562/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50562/tests/kraken/driver.html?testName=audio-fft) 02:22:44 INFO - PROCESS | 1967 | RSS: Main: 383975424 02:22:44 INFO - PROCESS | 1967 | 02:22:46 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50562/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50562/tests/kraken/driver.html?testName=audio-oscillator) 02:22:47 INFO - PROCESS | 1967 | RSS: Main: 283652096 02:22:47 INFO - PROCESS | 1967 | 02:22:49 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50562/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50562/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:22:50 INFO - PROCESS | 1967 | RSS: Main: 288591872 02:22:50 INFO - PROCESS | 1967 | 02:22:52 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50562/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50562/tests/kraken/driver.html?testName=imaging-darkroom) 02:22:53 INFO - PROCESS | 1967 | RSS: Main: 324653056 02:22:53 INFO - PROCESS | 1967 | 02:22:56 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50562/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50562/tests/kraken/driver.html?testName=imaging-desaturate) 02:22:57 INFO - PROCESS | 1967 | RSS: Main: 329043968 02:22:57 INFO - PROCESS | 1967 | 02:23:00 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50562/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50562/tests/kraken/driver.html?testName=json-parse-financial) 02:23:00 INFO - PROCESS | 1967 | RSS: Main: 328552448 02:23:00 INFO - PROCESS | 1967 | 02:23:02 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50562/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50562/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:23:03 INFO - PROCESS | 1967 | RSS: Main: 315625472 02:23:03 INFO - PROCESS | 1967 | 02:23:05 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50562/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50562/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:23:05 INFO - PROCESS | 1967 | RSS: Main: 322183168 02:23:05 INFO - PROCESS | 1967 | 02:23:08 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50562/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50562/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:23:08 INFO - PROCESS | 1967 | RSS: Main: 325607424 02:23:08 INFO - PROCESS | 1967 | 02:23:11 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50562/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50562/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:23:12 INFO - PROCESS | 1967 | RSS: Main: 329732096 02:23:12 INFO - PROCESS | 1967 | 02:23:15 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50562/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50562/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:23:16 INFO - PROCESS | 1967 | RSS: Main: 328327168 02:23:16 INFO - PROCESS | 1967 | 02:23:17 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:50562/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50562/tests/kraken/driver.html?testName=ai-astar) 02:23:18 INFO - PROCESS | 1967 | RSS: Main: 375570432 02:23:18 INFO - PROCESS | 1967 | 02:23:18 INFO - PROCESS | 1967 | __start_tp_report 02:23:18 INFO - PROCESS | 1967 | _x_x_mozilla_page_load 02:23:18 INFO - PROCESS | 1967 | _x_x_mozilla_page_load_details 02:23:18 INFO - PROCESS | 1967 | |i|pagename|runs| 02:23:18 INFO - PROCESS | 1967 | |0;ai-astar;114;106;112;113;113;112;112;113;113;112 02:23:18 INFO - PROCESS | 1967 | |1;audio-beat-detection;157;116;112;203;108;145;160;139;177;118 02:23:18 INFO - PROCESS | 1967 | |2;audio-dft;184;184;190;251;215;184;216;207;263;182 02:23:18 INFO - PROCESS | 1967 | |3;audio-fft;76;57;62;118;57;55;62;67;80;56 02:23:18 INFO - PROCESS | 1967 | |4;audio-oscillator;97;102;196;98;83;77;101;78;77;107 02:23:18 INFO - PROCESS | 1967 | |5;imaging-gaussian-blur;87;85;89;86;88;85;90;87;88;84 02:23:18 INFO - PROCESS | 1967 | |6;imaging-darkroom;94;95;94;94;97;95;95;94;95;94 02:23:18 INFO - PROCESS | 1967 | |7;imaging-desaturate;84;84;95;125;83;85;82;84;88;83 02:23:18 INFO - PROCESS | 1967 | |8;json-parse-financial;58;55;56;56;55;56;56;121;57;55 02:23:18 INFO - PROCESS | 1967 | |9;json-stringify-tinderbox;69;65;71;74;75;72;70;70;70;71 02:23:18 INFO - PROCESS | 1967 | |10;stanford-crypto-aes;86;86;86;84;85;84;81;95;84;86 02:23:18 INFO - PROCESS | 1967 | |11;stanford-crypto-ccm;140;140;138;134;136;135;139;139;219;153 02:23:18 INFO - PROCESS | 1967 | |12;stanford-crypto-pbkdf2;211;176;164;163;164;166;184;224;234;166 02:23:18 INFO - PROCESS | 1967 | |13;stanford-crypto-sha256-iterative;59;67;84;68;67;67;66;66;66;66 02:23:18 INFO - PROCESS | 1967 | __end_tp_report 02:23:18 INFO - PROCESS | 1967 | __start_cc_report 02:23:18 INFO - PROCESS | 1967 | _x_x_mozilla_cycle_collect,2298 02:23:18 INFO - PROCESS | 1967 | __end_cc_report 02:23:18 INFO - PROCESS | 1967 | __startTimestamp1463995398216__endTimestamp 02:23:18 INFO - PROCESS | 1967 | 02:23:18 INFO - PROCESS | 1967 | ------- Summary: start ------- 02:23:18 INFO - PROCESS | 1967 | Number of tests: 14 02:23:18 INFO - PROCESS | 1967 | 02:23:18 INFO - PROCESS | 1967 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:2.21 (2.0%) stddev-sans-first:2.22 02:23:18 INFO - PROCESS | 1967 | Values: 114.0 106.0 112.0 113.0 113.0 112.0 112.0 113.0 113.0 112.0 02:23:18 INFO - PROCESS | 1967 | 02:23:18 INFO - PROCESS | 1967 | [#1] audio-beat-detection Cycles:10 Average:143.50 Median:151.00 stddev:31.27 (20.7%) stddev-sans-first:32.78 02:23:18 INFO - PROCESS | 1967 | Values: 157.0 116.0 112.0 203.0 108.0 145.0 160.0 139.0 177.0 118.0 02:23:18 INFO - PROCESS | 1967 | 02:23:18 INFO - PROCESS | 1967 | [#2] audio-dft Cycles:10 Average:207.60 Median:211.00 stddev:29.28 (13.9%) stddev-sans-first:29.78 02:23:18 INFO - PROCESS | 1967 | Values: 184.0 184.0 190.0 251.0 215.0 184.0 216.0 207.0 263.0 182.0 02:23:18 INFO - PROCESS | 1967 | 02:23:18 INFO - PROCESS | 1967 | [#3] audio-fft Cycles:10 Average:69.00 Median:62.00 stddev:19.22 (31.0%) stddev-sans-first:20.22 02:23:18 INFO - PROCESS | 1967 | Values: 76.0 57.0 62.0 118.0 57.0 55.0 62.0 67.0 80.0 56.0 02:23:18 INFO - PROCESS | 1967 | 02:23:18 INFO - PROCESS | 1967 | [#4] audio-oscillator Cycles:10 Average:101.60 Median:77.50 stddev:35.10 (45.3%) stddev-sans-first:37.19 02:23:18 INFO - PROCESS | 1967 | Values: 97.0 102.0 196.0 98.0 83.0 77.0 101.0 78.0 77.0 107.0 02:23:18 INFO - PROCESS | 1967 | 02:23:18 INFO - PROCESS | 1967 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:87.50 stddev:1.91 (2.2%) stddev-sans-first:2.03 02:23:18 INFO - PROCESS | 1967 | Values: 87.0 85.0 89.0 86.0 88.0 85.0 90.0 87.0 88.0 84.0 02:23:18 INFO - PROCESS | 1967 | 02:23:18 INFO - PROCESS | 1967 | [#6] imaging-darkroom Cycles:10 Average:94.70 Median:95.00 stddev:0.95 (1.0%) stddev-sans-first:0.97 02:23:18 INFO - PROCESS | 1967 | Values: 94.0 95.0 94.0 94.0 97.0 95.0 95.0 94.0 95.0 94.0 02:23:18 INFO - PROCESS | 1967 | 02:23:18 INFO - PROCESS | 1967 | [#7] imaging-desaturate Cycles:10 Average:89.30 Median:84.00 stddev:13.10 (15.6%) stddev-sans-first:13.75 02:23:18 INFO - PROCESS | 1967 | Values: 84.0 84.0 95.0 125.0 83.0 85.0 82.0 84.0 88.0 83.0 02:23:18 INFO - PROCESS | 1967 | 02:23:18 INFO - PROCESS | 1967 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:56.00 stddev:20.58 (36.7%) stddev-sans-first:21.76 02:23:18 INFO - PROCESS | 1967 | Values: 58.0 55.0 56.0 56.0 55.0 56.0 56.0 121.0 57.0 55.0 02:23:18 INFO - PROCESS | 1967 | 02:23:18 INFO - PROCESS | 1967 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.00 stddev:2.75 (3.9%) stddev-sans-first:2.85 02:23:18 INFO - PROCESS | 1967 | Values: 69.0 65.0 71.0 74.0 75.0 72.0 70.0 70.0 70.0 71.0 02:23:18 INFO - PROCESS | 1967 | 02:23:18 INFO - PROCESS | 1967 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:86.00 stddev:3.62 (4.2%) stddev-sans-first:3.84 02:23:18 INFO - PROCESS | 1967 | Values: 86.0 86.0 86.0 84.0 85.0 84.0 81.0 95.0 84.0 86.0 02:23:18 INFO - PROCESS | 1967 | 02:23:18 INFO - PROCESS | 1967 | [#11] stanford-crypto-ccm Cycles:10 Average:147.30 Median:139.50 stddev:25.73 (18.4%) stddev-sans-first:27.16 02:23:18 INFO - PROCESS | 1967 | Values: 140.0 140.0 138.0 134.0 136.0 135.0 139.0 139.0 219.0 153.0 02:23:18 INFO - PROCESS | 1967 | 02:23:18 INFO - PROCESS | 1967 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.20 Median:180.00 stddev:27.42 (15.2%) stddev-sans-first:27.45 02:23:18 INFO - PROCESS | 1967 | Values: 211.0 176.0 164.0 163.0 164.0 166.0 184.0 224.0 234.0 166.0 02:23:18 INFO - PROCESS | 1967 | 02:23:18 INFO - PROCESS | 1967 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:67.00 stddev:6.28 (9.4%) stddev-sans-first:5.83 02:23:18 INFO - PROCESS | 1967 | Values: 59.0 67.0 84.0 68.0 67.0 67.0 66.0 66.0 66.0 66.0 02:23:18 INFO - PROCESS | 1967 | -------- Summary: end -------- 02:23:18 INFO - PROCESS | 1967 | 02:23:19 INFO - TEST-INFO | 1967: exit 0 02:23:19 INFO - TEST-OK | kraken | took 61416ms 02:23:19 INFO - SUITE-END | took 1175s 02:23:19 INFO - Completed test suite (00:19:35) 02:23:19 INFO - javascript benchmark 02:23:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2814.8451835149067, 3420.230448149165, 3447.7069449096825, 3465.116395319193, 3487.9476807847886, 5636.802711905079, 5657.830265092043, 5710.914336284954, 5741.856453588663, 5746.626469279494, 5710.914336284959, 5737.225474560933, 5739.225204597378, 5868.442497962697, 5880.147378630107, 5177.197534382177, 5206.921896171561, 5228.529432351088, 5280.0, 5376.435474275204, 5316.3354580677415, 5332.626716129873, 5370.624056316059, 5384.1923711443205, 5398.379186393576, 5141.177411614161, 5149.407818100929, 5226.973865130688, 5249.606279529051, 5251.002309561171, 5217.19133534299, 5228.215767634847, 5286.0, 5295.735213239323, 5304.947645278135, 5237.633365664405, 5310.230016647603, 5319.867003324909, 5340.145576707747, 5349.7057661828585, 5567.47109024642, 5604.2714447122025, 5663.377028526858, 5667.121596152603, 5709.172170035363, 5804.390538993429, 5931.911021334683, 6244.875102497925, 6320.241571011508, 6338.334474880117, 4982.825601103944, 6159.692015399212, 6233.283172435137, 6280.968595157041, 6295.937040629581, 5799.3910639382675, 5863.912041319383, 5871.442212989759, 5890.8821823563285, 5904.084866845646, 6276.686165691697, 6296.779612713533, 6305.873882522324, 6329.019002054689, 6332.841678958063, 6072.301685306158, 6076.0, 6197.03945888388, 6236.03341482071, 6258.530610204253, 6208.968955155286, 6274.403931626488, 6278.4035516625845, 6309.179806625201, 6320.652364119926, 6234.189555357774, 6269.7178626961895, 6288.308286088527, 6303.0, 6309.053641953652, 6143.262808463013, 6146.7091910699255, 6302.0, 6311.2426508819235, 6326.398992095744, 4650.441946966318, 4974.975125124423, 4980.377452818398, 5000.924986125211, 5027.99440111979, 3885.922281554353, 4427.048184640254, 4478.708883922534, 4485.349624304457, 4501.68488205829, 5571.804986825442, 5587.05020146576, 5711.571632127607, 5762.423757624204, 5780.277465316835, 5198.324217851655, 6258.968705156536, 6260.780872669526, 6273.780417685451, 6284.465820405317, 5173.896522069613, 5576.358718747396, 5793.2179155814265, 5827.883442331215, 5828.242328497268, 5352.678839269656, 5430.701311427831, 5475.069238229431, 5479.780808767684, 5487.1494918286935, 6409.871802563923, 6411.455026322815, 6414.807555773335, 6432.742690292336, 6457.61254324742, 6262.53031022666, 6309.211348581427, 6311.0217916223055, 6314.336994615683, 6321.557490975588, 6187.535934804998, 6238.469730072813, 6259.3427690091885, 6297.716602752796, 6419.807405777834, 5731.356384437635, 6200.348963358782, 6233.283172435046, 6247.906281405873, 6257.812265632038, 6241.750329986749, 6305.306416294112, 6306.0, 6311.179546658905, 6322.36776322364, 5868.794592189253, 6080.268688454573, 6097.857415703088, 6125.632272693224, 6172.598781079395, 6184.5980011299125, 6199.349068347939, 6205.844853878689, 6211.596246243979, 6214.223222097238, 5874.500667443144, 6065.302490213682, 6085.786997455068, 6086.786962456293, 6091.634501929899, 6105.412836282587, 6108.448827822033, 6119.112728654409, 6218.906716399348, 6224.128621992834, 6016.819495415145, 6057.394260573908, 6069.02895536712, 6079.422454866781, 6093.129467831542], "value": 5720.736170449654, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.8128492457791, 506.52386756448936, 507.7892674540066, 509.3378607809846, 557.9051561234593, 42077.57922420775, 43495.56504434955, 43552.0, 43741.0, 45856.54143458564, 43903.78048109755, 44417.55582444175, 44454.33318500225, 44458.555414445844, 44546.33180502295, 9609.135177833976, 9826.656067037655, 9828.754281142958, 9830.852537211911, 9835.065668761492, 9536.809263814719, 9628.229741620653, 9695.321327507078, 9717.659881904136, 9722.319437639368, 9643.710688679352, 9733.56198971048, 9774.853377199348, 9784.95107524465, 9814.558344874498, 9714.951425242863, 9717.708468745914, 9757.316987810857, 9757.609695612167, 9759.219262459057, 9493.0981556752, 9552.283578731623, 9607.663731769355, 9612.038796120332, 9642.614295428175, 40939.59060409388, 41435.171296574204, 41484.58515414837, 41490.0, 41541.96145096367, 3072.201227680797, 3166.0976621662717, 3169.0, 3569.625189355132, 3846.3845784674318, 29281.6229944011, 29339.82640694391, 30024.69975300241, 30524.54213186804, 30670.386592268253, 2709.078913169532, 2727.9318017049536, 2730.331068888135, 2835.773138148964, 2886.9133925982255, 506151.99999999633, 513112.0, 513269.4336528293, 513790.0, 515408.0, 61636.0, 62149.68925155346, 62553.374466254754, 62569.68715156395, 62729.0, 56551.434485654616, 57975.0, 64751.676241618494, 66522.66738666275, 66557.66721166363, 16576.502704918872, 16769.580760480843, 16786.160691965353, 16786.916065419595, 16827.579310517336, 11689.532418703157, 12890.29103399322, 12990.610281691375, 12995.610131696065, 12999.1550549214, 16950.49148525546, 17348.3060677574, 17356.305747769948, 17456.0, 17521.474355769347, 5857.589968702235, 6558.80323590293, 6610.636414997125, 6638.83402914931, 6656.30108838565, 914.8810654614857, 1014.9695509134738, 1054.6361505280665, 1077.4828082520435, 1109.3842917181041, 28702.85648571744, 29512.0, 29548.409031819243, 29935.401291974038, 30204.395912082076, 1072.1476426241156, 1104.9226554141294, 1146.853840621057, 1148.9827652585218, 1171.6485054483621, 14428.711425771426, 15206.847931520764, 15285.770713439308, 15586.454474093352, 15604.687906241812, 627492.0, 637560.0, 639034.0, 640789.0, 643636.0, 60408.0, 60565.394346056855, 60590.39409605935, 60649.69675151684, 60765.0, 1303.9348032598523, 1349.5479014530242, 1429.8641629045223, 1442.2500299844023, 1475.4835807467516, 24540.772961351504, 30042.0, 30141.095767127023, 30708.385832283675, 32620.673793262715, 52851.207231892324, 53126.4687353137, 53404.0, 53427.0, 53728.0, 546398.0, 550746.0, 551774.0, 555388.2230588822, 564593.0, 39341.6065839338, 39953.601623943025, 40813.59186408217, 40951.59048409478, 41029.38455923104, 45627.859303517675, 46607.30089048599, 46678.53321466878, 46821.765891170326, 48152.759236203594, 38481.8455446337, 38842.417363739, 39163.02092447712, 39910.00224994282, 40277.99305017398, 35773.284534309685, 37819.62180378161, 37969.62030379661, 38941.66350019008, 39027.41458878062, 35576.46635300421, 36894.446583300734, 37885.242295154494, 38619.613803861605, 40241.59758402379, 30988.0, 31496.527552087195, 33709.15727106842, 34316.14209644679, 34619.30761384808, 24640.50718985646, 25021.374465638506, 25220.0, 25439.982400703764, 25511.6173257405], "value": 20239.770437874107, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.9641884021018, 382.35763916620084, 383.9174577465845, 394.96247856453624, 399.16773527195795, 19196.808031919678, 19999.800001999974, 20374.0, 20720.792792072094, 20723.378298651027, 20097.397078087644, 20649.174033038697, 20685.069171887262, 21089.894550527264, 21310.54928683198, 5738.651416917021, 5898.970505147479, 6107.32819389869, 6118.63288202707, 6133.2026836511195, 5869.471747542721, 5972.761089556412, 6018.638881667114, 6078.939210607881, 6089.147519347295, 5808.070708686611, 5868.618539794899, 5971.4327138921735, 5981.760729570812, 5991.161237426763, 5919.911201331984, 5965.135055416962, 5969.88060238795, 5974.462298393166, 6007.789727359544, 5102.6173037022, 5168.612354073422, 5203.713795741232, 5207.375114986188, 5213.009528189632, 20404.0, 24329.0, 24600.876995614908, 25036.0, 25137.371565710822, 2109.841761867866, 2136.7756385579446, 2242.461809165805, 2255.289583781104, 2260.9095636174534, 14471.78292325616, 14571.489997850025, 15003.699926001418, 15162.620934476616, 15564.61088472786, 1688.1137402863471, 1744.8953062816272, 1782.1801971093312, 1788.9105544722843, 1928.2672584417971, 90176.54911725399, 97963.73305609975, 132137.33931330283, 133722.33138834243, 140978.0, 20326.00382887052, 20408.591828163353, 20779.68830467544, 20907.47731306699, 20935.812837432575, 34766.65233347685, 35625.82187089048, 35716.107097322776, 35783.82108089443, 35869.82065089658, 10360.378377297386, 10813.459327033617, 10988.835167472495, 11019.283746556448, 11167.218294719454, 8933.46399216049, 8939.776505587282, 8977.685780997634, 8993.23557497607, 9061.637534498546, 9669.226461883032, 9716.514174291256, 9745.317827752131, 9795.951020244996, 9867.950660246654, 3238.9676103239135, 3241.967580324214, 3688.5102555774743, 3731.9253614928093, 3785.716071294664, 886.6586364249811, 941.665708673411, 946.3186505715873, 959.5058544849409, 968.864358989742, 19024.858508489535, 19708.817470951788, 19868.0, 19919.900400497903, 20081.899590501955, 910.5128756115579, 924.6209054287709, 963.8698775665196, 977.2621670638622, 983.3460748602242, 10383.37699738003, 10589.15286777056, 10601.946990265, 10612.681619551426, 10619.469026548642, 561789.0, 582585.0, 583870.0806495941, 608648.0, 610876.0, 121897.39051304686, 125103.374483127, 127038.0, 130455.0, 135832.3208383952, 887.9156480134377, 936.2556767369821, 974.541965276319, 996.4220751963844, 1013.3463915774284, 17414.564635884206, 17629.382971595933, 17716.91141544284, 17731.11344432799, 17884.374046908295, 42415.7879210602, 45008.5245311511, 52964.0, 53785.46214537804, 54473.72763136159, 69455.0, 73660.0, 73878.63060684662, 75062.49875002578, 83451.0, 6775.593464392152, 7089.574625522485, 7177.497575169688, 7253.528520646141, 7349.853002940018, 7570.9242907572425, 9000.594973226089, 9874.753131171778, 10056.64801731936, 10317.432541210306, 7327.303906128909, 8604.78488037804, 9035.277177825626, 9035.864462032943, 9284.071592840932, 8625.309975201842, 8886.333524985523, 8923.955380223057, 9037.231835294073, 9159.77100572491, 8749.0, 9617.0, 10137.189024877845, 10231.0, 10563.683089507327, 7609.162992070757, 7651.808704782425, 7705.30652241301, 7744.225577442211, 7749.380049596131, 7036.648167591702, 10233.539490723084, 10263.948680256552, 10316.381017138996, 10464.476776161313], "value": 10115.19273414216, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1387.3271463340284, 1577.5188567486912, 1586.4209563509319, 1852.7869295031066, 1999.1203870297068, 7838.451308408414, 8647.43791653543, 8822.117788221183, 8844.0, 8997.325200609965, 8511.404201705884, 8807.95596022019, 8820.338474614413, 8829.426087304337, 8830.116988301166, 1988.2245924089543, 2333.579955607994, 2351.2240960483, 2357.281029286067, 2362.173239366217, 2311.849729767568, 2345.95308093838, 2350.8236882233814, 2352.164981431593, 2352.9882350588223, 2308.68832707584, 2315.2822624986306, 2339.4502291961376, 2347.7769611886843, 2357.363511851799, 2290.816734661223, 2295.9755653989414, 2308.4459729664845, 2324.569954558412, 2335.708036495438, 2220.134147682389, 2229.4092065602626, 2230.174835310945, 2230.565039817236, 2232.9330120096424, 8580.82838343236, 8709.95645021777, 8733.432326898732, 8773.868391974125, 8787.692430764957, 4788.802799300175, 4898.436679781799, 5117.283580298761, 5250.6324557281005, 5255.737213139328, 3265.0694552052787, 3368.9449435844117, 3380.6619338066243, 3389.627141014511, 3466.0988143082727, 1004.1565085328359, 1022.437659287389, 1023.5649848814298, 1027.106417416852, 1028.6759670703705, 11580.652580422517, 11665.708357291136, 11694.0, 11715.648530544097, 11722.0, 4414.337849322627, 4423.778811059434, 4456.376107344974, 4480.14877173336, 4550.681452298308, 8995.190432860943, 9023.639054437881, 9102.81794364109, 9129.0, 9147.176754092163, 5074.898502029939, 5141.691498510101, 5145.305383773143, 5145.819896303611, 5149.716765577929, 4347.130573885235, 4472.9776351118035, 4668.0, 4766.904661906742, 4799.232122860326, 3749.2314075614436, 3780.7920564368674, 3808.6762625176616, 3850.4801851749653, 3898.4542164097047, 1161.529580519874, 1169.3217933598492, 1169.8947094761513, 1173.6537721372215, 1186.5965571705663, 643.8165122940034, 798.9121196668361, 878.6221924572495, 924.0251534631029, 932.6988850553576, 4605.976970115128, 5076.756194732314, 5598.664080155204, 5714.914276285859, 5845.240118784616, 464.46586425610144, 465.42984843567035, 477.84708893153856, 481.4319103457953, 620.8509957610141, 3740.8690695825517, 3842.5965273646425, 3933.645971862546, 3938.6849052075763, 3951.5258169019326, 10467.214958877963, 10760.031597156294, 10802.351858888334, 10896.509657065451, 10945.398003109905, 10643.361398316125, 10827.83758243627, 10853.945730271456, 10854.0, 10874.72813179661, 7918.089419716691, 8219.1369906159, 8282.71010514617, 8286.875696864552, 8288.0, 5598.468145526169, 5661.490465858093, 5709.68596727184, 5714.942850571608, 5731.627444216113, 9680.177184939359, 10076.395416275047, 10142.391456512632, 10148.0, 10154.949225253826, 9573.18627916635, 10002.399856008662, 10048.995100490185, 10244.436555989492, 10254.487275636338, 7018.508704390848, 7200.279972002758, 7259.382952449101, 7295.066807296177, 7436.96281518589, 8314.043884953307, 8322.833543329221, 8406.621702023302, 8413.579321033802, 8434.198751118634, 6239.532035097295, 7325.194228634739, 7421.628918554159, 7429.294217049372, 7571.318581327487, 7290.708371665074, 7334.266573342623, 7411.814704632212, 7450.478466507292, 7500.749775067349, 6919.308069193041, 6948.722051117898, 6956.617386043817, 7004.194517630334, 7020.473464490286, 3716.4053751399647, 4695.953040469688, 4837.854864354075, 4882.194437917846, 4960.9829984852295, 4349.217140914539, 4846.757662116951, 4924.975375123101, 4964.925526117039, 5011.09800235961], "value": 4402.684502086532, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [257.8427159432746, 293.8002158532198, 305.71873876034056, 309.9612548431446, 310.61173533083644, 4740.810367585301, 5110.310108135401, 5231.398389185245, 5267.97091672288, 5294.444083371249, 4623.953760462395, 5159.432462429129, 5245.947540524594, 5259.263703081571, 5263.6841789492655, 3978.264021156095, 3983.960160398388, 3998.9800050999706, 4001.779902105383, 4043.2722110020186, 3534.692163899361, 3860.7297489175767, 3869.7678139311734, 3988.6809055275507, 4001.539822920358, 3327.5341452196712, 3519.9296014079705, 3876.9532226298884, 3955.564887862333, 4043.2317859606583, 3948.019245469652, 3965.1871366369824, 3975.6819454443576, 3996.1208534122447, 4002.6597739192202, 3350.8324583770714, 3497.545319108525, 3499.0377646147513, 3506.6668666476644, 3531.2937412517344, 4306.35404689296, 4537.727736335831, 4726.787294571718, 4959.20652695567, 5034.345535080416, 4498.797554110073, 4553.658475614342, 4557.270836666117, 4563.817447302104, 4583.593613352983, 4518.390017347649, 5472.644278121909, 5500.477454641803, 5536.446355364472, 5548.55611551075, 3459.775114617542, 3795.905102372435, 3802.676772474343, 3805.6384643459105, 3829.9808500957597, 5805.82582522525, 5812.564057695689, 5836.6789826559525, 5847.8538036549, 5856.707164641751, 3816.6260146347295, 3826.674732647756, 3840.5967373425947, 3849.826757795906, 3851.7496362736333, 3361.781484203519, 3762.7177961652988, 3911.4745249352777, 3922.470466486988, 3933.6066393361007, 3518.5249991250853, 4085.530164031115, 4277.636400905958, 4453.710508816916, 4478.894060770724, 5032.72320022395, 5515.001623822776, 5546.639468434539, 5594.9720251398485, 5621.213030175779, 1170.9004734597484, 1267.512007876974, 1270.841144856893, 1316.4207748590588, 1320.3530270167741, 729.5440349781387, 774.9651265693058, 787.46452412359, 800.7828101286012, 810.3355248696131, 3814.5422549294262, 3978.522577290744, 3990.082281075369, 4186.990244682569, 4400.603945644843, 4172.749635021848, 4321.632661223831, 4526.660500462479, 4592.931106033412, 4783.976080119577, 3677.3932301170544, 3736.4208547675153, 4049.4128351388886, 4284.742915425085, 4309.525952145262, 2400.3596044351075, 2404.8156059089742, 2524.255344673326, 2544.974550254474, 2564.5127425789415, 4224.2331273016225, 4886.33694636424, 4899.951000489949, 4910.930668332292, 4917.0, 4159.708820382604, 4372.890677733082, 4573.719358579603, 4686.8125274988615, 4882.926756098661, 4335.414719012893, 4344.652427805768, 4386.627136693289, 4798.008826337101, 4888.902221955541, 4590.74750888691, 4623.352730617784, 4752.6197904168275, 4792.472827988988, 4847.927281090857, 3985.900352491211, 4023.7384570002855, 4325.913481730285, 4680.251159814414, 4714.346381554185, 3990.680745540292, 4380.986771157126, 4437.312216606302, 4812.759362031815, 4935.790731270861, 2865.9426811464073, 3005.5341422079614, 3060.5515587529444, 3208.855601497985, 3267.493538501536, 4087.7956102194175, 4273.87178384649, 4506.346579745984, 4545.272756358967, 4739.502352252964, 4821.445533763662, 5009.670503216022, 5430.294061771944, 5549.500544950813, 5582.665040097607, 5050.621203409685, 5271.420143784258, 5530.170474428707, 5532.197831314517, 5546.389897111395, 4869.975650121727, 5059.848204553869, 5081.586246532867, 5082.695038297714, 5427.561696150445, 4719.59883409914, 4820.421549414093, 5575.132330669271, 5586.86032849182, 5624.381318054929, 5336.2529245904825, 5436.151708419156, 5499.725013749377, 5517.834464966058, 5553.72231388437], "value": 3753.1899601577047, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [647.4334956912701, 727.9199288078314, 743.1453828097689, 744.8026273037642, 759.9886001709979, 15097.396104155834, 15380.154091524962, 16938.999999999985, 17728.734068988942, 17758.46724598264, 16605.0, 16633.750493742602, 16663.91668041658, 16704.99770013797, 16766.07786571738, 725.0972539188691, 751.4927423988786, 928.1924725488834, 942.4580866002042, 944.707140786355, 904.7421484876811, 941.5715849288591, 941.6186444489992, 943.3726571829725, 944.4239014201331, 982.2829334585757, 993.0466751918167, 994.5077186792548, 994.8706668133133, 998.3161534348994, 771.2595907928395, 930.3719989007427, 930.972070837876, 962.6630679262272, 998.211412983742, 713.1371041040347, 719.6617589732817, 720.6000669628354, 733.5342057793287, 734.4454936522944, 15268.0, 15361.15513646749, 15379.077255364602, 15385.153816540085, 15403.537893863202, 10588.94705526475, 12906.0, 12973.481060757558, 13093.41079651418, 13113.475460981645, 9640.0, 9677.564509597085, 9691.34879082523, 9706.0, 10494.842577361345, 4123.2165888481095, 4134.958650413518, 4295.6778241632, 4428.822847086113, 4432.578905004019, 80270.0, 80712.19287807106, 81763.0, 81970.0, 82703.58648206781, 9334.299927505464, 9585.993470685617, 9768.706938791847, 9956.850647240297, 10356.844647330296, 17575.29698812033, 21610.45973850635, 21694.566108677736, 21729.348119556438, 21736.239231627133, 7325.890111648329, 7409.592472414069, 7465.29079737424, 7474.551526908294, 7510.84978300431, 3919.470871432377, 3972.2651309507833, 3979.0, 3980.3233450313514, 3987.282289187916, 2738.7261273872455, 3227.967720322814, 3236.0, 3299.9010029699148, 3315.867365305409, 553.6816330609915, 576.4840467781396, 577.1371799160282, 577.3014652270715, 584.748558120004, 452.76682508508145, 539.3339226055818, 560.778492495462, 600.9278886533644, 613.3345320327495, 7989.0, 8115.918840811634, 8316.6673333066, 8332.919205271646, 8385.454945428528, 493.4448745161693, 494.77240469384486, 580.1877371679682, 600.0, 607.4593611685603, 3803.581606023335, 3860.82626281818, 3866.7486613370043, 3902.921941561153, 4016.4117866467736, 76698.2330176691, 79592.0, 80650.5967470159, 81413.59293203497, 84895.72656410158, 47164.0, 72843.0, 75420.62289688626, 77093.22906770973, 77848.61075694585, 63849.680751595944, 64462.6776866122, 64998.350016500175, 65129.697406051615, 67328.32671673318, 7589.0, 7593.164751877179, 7824.726134585262, 7867.449278550446, 7891.447598668267, 42993.78503107464, 43014.56985430106, 43029.784851076794, 43194.568054319054, 43253.0, 57496.52545169101, 58930.0, 59801.70099149476, 60195.39804602073, 60239.698801505714, 10396.948015259875, 10424.270301079156, 10511.159107271553, 10783.352998820095, 11380.374079425712, 10856.45717714127, 10863.67408977732, 11030.117590592572, 11398.974092331398, 11447.599334023269, 10510.0, 10627.840582391425, 10778.676639700821, 10791.56833726642, 10796.406197659204, 10343.0, 10365.74085647865, 10441.164706823587, 10455.529501172618, 10738.248322617343, 10073.848892266475, 10135.695929122137, 10611.469426528796, 10662.786744265226, 10673.733156671145, 7425.665845036879, 8753.562321884008, 8959.328050396376, 9059.728208153765, 9154.359194856295, 7893.644785984759, 8872.86690699627, 8879.467231966102, 8922.241609463268, 9062.320325975446], "value": 6712.836950990275, "unit": "score"}], "name": "dromaeo_css", "value": 7116.801389911375, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 106.0, 112.0, 113.0, 113.0, 112.0, 112.0, 113.0, 113.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 116.0, 112.0, 203.0, 108.0, 145.0, 160.0, 139.0, 177.0, 118.0], "value": 143.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 190.0, 251.0, 215.0, 184.0, 216.0, 207.0, 263.0, 182.0], "value": 207.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 57.0, 62.0, 118.0, 57.0, 55.0, 62.0, 67.0, 80.0, 56.0], "value": 69.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 102.0, 196.0, 98.0, 83.0, 77.0, 101.0, 78.0, 77.0, 107.0], "value": 101.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 89.0, 86.0, 88.0, 85.0, 90.0, 87.0, 88.0, 84.0], "value": 86.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 94.0, 97.0, 95.0, 95.0, 94.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 95.0, 125.0, 83.0, 85.0, 82.0, 84.0, 88.0, 83.0], "value": 89.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 55.0, 56.0, 56.0, 55.0, 56.0, 56.0, 121.0, 57.0, 55.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 71.0, 74.0, 75.0, 72.0, 70.0, 70.0, 70.0, 71.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 84.0, 85.0, 84.0, 81.0, 95.0, 84.0, 86.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 138.0, 134.0, 136.0, 135.0, 139.0, 139.0, 219.0, 153.0], "value": 147.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [211.0, 176.0, 164.0, 163.0, 164.0, 166.0, 184.0, 224.0, 234.0, 166.0], "value": 185.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 67.0, 84.0, 68.0, 67.0, 67.0, 66.0, 66.0, 66.0, 66.0], "value": 67.6, "unit": "score"}], "name": "kraken", "value": 1523.6, "alertThreshold": 2.0}]} 02:23:19 INFO - Return code: 0 02:23:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:23:19 ERROR - # TBPL SUCCESS # 02:23:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:23:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:23:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:23:19 INFO - 2016-05-23 02:23:19.530 screenresolution[1971:64327] starting screenresolution argv=screenresolution get 02:23:19 INFO - 2016-05-23 02:23:19.533 screenresolution[1971:64327] Display 0: 1600x1200x32@60 02:23:19 INFO - 2016-05-23 02:23:19.539 screenresolution[1972:64331] starting screenresolution argv=screenresolution list 02:23:19 INFO - Available Modes on Display 0 02:23:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:23:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:23:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:23:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:23:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:23:19 INFO - Intel Iris: 02:23:19 INFO - Chipset Model: Intel Iris 02:23:19 INFO - Type: GPU 02:23:19 INFO - Bus: Built-In 02:23:19 INFO - VRAM (Dynamic, Max): 1536 MB 02:23:19 INFO - Vendor: Intel (0x8086) 02:23:19 INFO - Device ID: 0x0a2e 02:23:19 INFO - Revision ID: 0x0009 02:23:19 INFO - Displays: 02:23:19 INFO - Display: 02:23:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:23:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:23:19 INFO - Display Serial Number: 1600x1200 60 02:23:19 INFO - Main Display: Yes 02:23:19 INFO - Mirror: Off 02:23:19 INFO - Online: Yes 02:23:19 INFO - Rotation: Supported 02:23:19 INFO - Return code: 0 02:23:19 INFO - Running post-action listener: _resource_record_post_action 02:23:19 INFO - ##### 02:23:19 INFO - ##### Finished run-tests step (success) 02:23:19 INFO - ##### 02:23:19 INFO - Running post-run listener: _resource_record_post_run 02:23:19 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 0; Write bytes: 694470656; Read time: 0; Write time: 1873 02:23:19 INFO - TinderboxPrint: CPU usage
54.1% 02:23:19 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 02:23:19 INFO - TinderboxPrint: I/O write bytes / time
694,470,656 / 1,873 02:23:19 INFO - TinderboxPrint: CPU idle
2,224.8 (45.9%) 02:23:19 INFO - TinderboxPrint: CPU system
973.6 (20.1%) 02:23:19 INFO - TinderboxPrint: CPU user
1,647.3 (34.0%) 02:23:19 INFO - TinderboxPrint: Swap in / out
395,571,200 / 0 02:23:19 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 375331328; Write bytes: 369522176; Read time: 28078; Write time: 733 02:23:19 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 0; Write bytes: 295793152; Read time: 0; Write time: 1070 02:23:20 INFO - Running post-run listener: _upload_blobber_files 02:23:20 INFO - Blob upload gear active. 02:23:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:23:20 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 02:23:20 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'] 02:23:20 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 02:23:20 INFO - (blobuploader) - INFO - Open directory for files ... 02:23:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:23:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:23:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:23:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:23:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:23:21 INFO - (blobuploader) - INFO - Done attempting. 02:23:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:23:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:23:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:23:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:23:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:23:22 INFO - (blobuploader) - INFO - Done attempting. 02:23:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:23:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:23:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:23:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:23:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:23:24 INFO - (blobuploader) - INFO - Done attempting. 02:23:24 INFO - (blobuploader) - INFO - Iteration through files over. 02:23:24 INFO - Return code: 0 02:23:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:23:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:23:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8c7036efb487c0a198387311df55bc90e670f41d8e801249d4989a48c6d6aaa7dfa31ef3828819ba971dd1a5f5e7735e1c98b3c28d5f52261cdb15e5dae5565", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/774e56cb6fed5afee7bfec29776315ebc2e1dfaca8d677c3e3681d1453a8bf842d4b74b4e942067c0af83542d6963d54972723e24a9adbac0f1c69d76e5035e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e67a59321a1d2ff754b88c5cdc2df7c784cb379685192cace88a16040801ac25316e71bc54fccc4718ce1bc374ab9780876a0f77c1fb281aeaed15be8f695404"} 02:23:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:23:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:23:24 INFO - Contents: 02:23:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8c7036efb487c0a198387311df55bc90e670f41d8e801249d4989a48c6d6aaa7dfa31ef3828819ba971dd1a5f5e7735e1c98b3c28d5f52261cdb15e5dae5565", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/774e56cb6fed5afee7bfec29776315ebc2e1dfaca8d677c3e3681d1453a8bf842d4b74b4e942067c0af83542d6963d54972723e24a9adbac0f1c69d76e5035e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e67a59321a1d2ff754b88c5cdc2df7c784cb379685192cace88a16040801ac25316e71bc54fccc4718ce1bc374ab9780876a0f77c1fb281aeaed15be8f695404"} 02:23:24 INFO - Running post-run listener: copy_logs_to_upload_dir 02:23:24 INFO - Copying logs to upload dir... 02:23:24 INFO - mkdir: /builds/slave/test/build/upload/logs 02:23:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.856065 ========= master_lag: 1.53 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-23 02:23:26.891179) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-05-23 02:23:26.892394) ========= 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.LNK7LvzO9c/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.RtWn47hrxU/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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/c8c7036efb487c0a198387311df55bc90e670f41d8e801249d4989a48c6d6aaa7dfa31ef3828819ba971dd1a5f5e7735e1c98b3c28d5f52261cdb15e5dae5565", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/774e56cb6fed5afee7bfec29776315ebc2e1dfaca8d677c3e3681d1453a8bf842d4b74b4e942067c0af83542d6963d54972723e24a9adbac0f1c69d76e5035e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e67a59321a1d2ff754b88c5cdc2df7c784cb379685192cace88a16040801ac25316e71bc54fccc4718ce1bc374ab9780876a0f77c1fb281aeaed15be8f695404"} build_url:https://queue.taskcluster.net/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.012766 build_url: 'https://queue.taskcluster.net/v1/task/VrkLd1FEQ0Oj0VeNPQuo8w/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/c8c7036efb487c0a198387311df55bc90e670f41d8e801249d4989a48c6d6aaa7dfa31ef3828819ba971dd1a5f5e7735e1c98b3c28d5f52261cdb15e5dae5565", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/774e56cb6fed5afee7bfec29776315ebc2e1dfaca8d677c3e3681d1453a8bf842d4b74b4e942067c0af83542d6963d54972723e24a9adbac0f1c69d76e5035e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e67a59321a1d2ff754b88c5cdc2df7c784cb379685192cace88a16040801ac25316e71bc54fccc4718ce1bc374ab9780876a0f77c1fb281aeaed15be8f695404"}' ========= master_lag: 4.50 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-05-23 02:23:31.401735) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 02:23:31.402214) ========= 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.LNK7LvzO9c/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.RtWn47hrxU/Listeners TMPDIR=/var/folders/j6/y00m2vkj0zb5sshv62f_4x8800000w/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.005119 ========= master_lag: 0.51 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 02:23:31.913504) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 02:23:31.916335) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 02:23:32.425056) ========= ========= Total master_lag: 6.60 =========