builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0110 starttime: 1464892273.22 results: success (0) buildid: 20160602100536 builduid: 5f8569912b944849be72112f8818e601 revision: 91879ebba4d61fde72af13223708faa01109a4d9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 11:31:13.221934) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 11:31:13.222417) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 11:31:13.222692) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 11:31:13.300035) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 11:31:13.300315) ========= 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.r2btpFwnF0/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.KlVFz3X0iC/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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-06-02 11:31:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.12M=0.001s 2016-06-02 11:31:13 (8.12 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.494068 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 11:31:13.921845) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 11:31:13.922635) ========= 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.r2btpFwnF0/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.KlVFz3X0iC/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.020810 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 11:31:13.966850) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-02 11:31:13.967197) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 91879ebba4d61fde72af13223708faa01109a4d9 --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 91879ebba4d61fde72af13223708faa01109a4d9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.r2btpFwnF0/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.KlVFz3X0iC/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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-06-02 11:31:14,041 truncating revision to first 12 chars 2016-06-02 11:31:14,041 Setting DEBUG logging. 2016-06-02 11:31:14,042 attempt 1/10 2016-06-02 11:31:14,042 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/91879ebba4d6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 11:31:15,991 unpacking tar archive at: fx-team-91879ebba4d6/testing/mozharness/ program finished with exit code 0 elapsedTime=2.205825 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-02 11:31:16.193168) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 11:31:16.193510) ========= script_repo_revision: 91879ebba4d61fde72af13223708faa01109a4d9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 11:31:16.193928) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 11:31:16.194213) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 11:31:16.210219) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 35 secs) (at 2016-06-02 11:31:16.210489) ========= /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.r2btpFwnF0/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.KlVFz3X0iC/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 11:31:16 INFO - MultiFileLogger online at 20160602 11:31:16 in /builds/slave/test 11:31: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 11:31:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:31:16 INFO - {'append_to_log': False, 11:31:16 INFO - 'base_work_dir': '/builds/slave/test', 11:31:16 INFO - 'blob_upload_branch': 'Fx-Team', 11:31:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:31:16 INFO - 'branch': 'Fx-Team', 11:31:16 INFO - 'buildbot_json_path': 'buildprops.json', 11:31:16 INFO - 'config_files': ('talos/mac_config.py',), 11:31:16 INFO - 'default_actions': ('clobber', 11:31:16 INFO - 'read-buildbot-config', 11:31:16 INFO - 'download-and-extract', 11:31:16 INFO - 'populate-webroot', 11:31:16 INFO - 'create-virtualenv', 11:31:16 INFO - 'install', 11:31:16 INFO - 'run-tests'), 11:31:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:31:16 INFO - 'download_minidump_stackwalk': True, 11:31:16 INFO - 'download_symbols': 'ondemand', 11:31:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:31:16 INFO - 'tooltool.py': '/tools/tooltool.py', 11:31:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:31:16 INFO - '/tools/misc-python/virtualenv.py')}, 11:31:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:31:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:31:16 INFO - 'installer_path': 'installer.exe', 11:31:16 INFO - 'log_level': 'info', 11:31:16 INFO - 'log_name': 'talos', 11:31:16 INFO - 'log_to_console': True, 11:31:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:31:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:31:16 INFO - 'opt_config_files': (), 11:31:16 INFO - 'pip_index': False, 11:31:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:31:16 INFO - 'cmd': ('bash', 11:31:16 INFO - '-c', 11:31:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:31:16 INFO - 'enabled': True, 11:31:16 INFO - 'halt_on_failure': False, 11:31:16 INFO - 'name': 'check_screen_resolution'},), 11:31:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:31:16 INFO - 'cmd': ('bash', 11:31:16 INFO - '-c', 11:31:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:31:16 INFO - 'enabled': True, 11:31:16 INFO - 'halt_on_failure': False, 11:31:16 INFO - 'name': 'check_screen_resolution'},), 11:31:16 INFO - 'run_cmd_checks_enabled': True, 11:31:16 INFO - 'sps_profile': False, 11:31:16 INFO - 'sps_profile_interval': 0, 11:31:16 INFO - 'suite': 'dromaeojs', 11:31:16 INFO - 'system_bits': '32', 11:31:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:31:16 INFO - 'title': 't-yosemite-r7-0110', 11:31:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:31:16 INFO - 'use_talos_json': True, 11:31:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:31:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:31:16 INFO - 'work_dir': 'build'} 11:31:16 INFO - ##### 11:31:16 INFO - ##### Running clobber step. 11:31:16 INFO - ##### 11:31:16 INFO - Running pre-action listener: _resource_record_pre_action 11:31:16 INFO - Running main action method: clobber 11:31:16 INFO - rmtree: /builds/slave/test/build 11:31:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:31:17 INFO - Running post-action listener: _resource_record_post_action 11:31:17 INFO - ##### 11:31:17 INFO - ##### Finished clobber step (success) 11:31:17 INFO - ##### 11:31:17 INFO - ##### 11:31:17 INFO - ##### Running read-buildbot-config step. 11:31:17 INFO - ##### 11:31:17 INFO - Running pre-action listener: _resource_record_pre_action 11:31:17 INFO - Running main action method: read_buildbot_config 11:31:17 INFO - Using buildbot properties: 11:31:17 INFO - { 11:31:17 INFO - "project": "", 11:31:17 INFO - "product": "firefox", 11:31:17 INFO - "script_repo_revision": "production", 11:31:17 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 11:31:17 INFO - "repository": "", 11:31:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 11:31:17 INFO - "buildid": "20160602100536", 11:31:17 INFO - "pgo_build": "False", 11:31:17 INFO - "basedir": "/builds/slave/test", 11:31:17 INFO - "buildnumber": 743, 11:31:17 INFO - "slavename": "t-yosemite-r7-0110", 11:31:17 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 11:31:17 INFO - "platform": "yosemite_r7", 11:31:17 INFO - "scheduler": "tests-fx-team-macosx64-talos", 11:31:17 INFO - "branch": "fx-team", 11:31:17 INFO - "repo_path": "integration/fx-team", 11:31:17 INFO - "revision": "91879ebba4d61fde72af13223708faa01109a4d9", 11:31:17 INFO - "stage_platform": "macosx64", 11:31:17 INFO - "builduid": "5f8569912b944849be72112f8818e601", 11:31:17 INFO - "slavebuilddir": "test" 11:31:17 INFO - } 11:31:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 11:31:17 INFO - Running post-action listener: _resource_record_post_action 11:31:17 INFO - ##### 11:31:17 INFO - ##### Finished read-buildbot-config step (success) 11:31:17 INFO - ##### 11:31:17 INFO - ##### 11:31:17 INFO - ##### Running download-and-extract step. 11:31:17 INFO - ##### 11:31:17 INFO - Running pre-action listener: _resource_record_pre_action 11:31:17 INFO - Running main action method: download_and_extract 11:31:17 INFO - mkdir: /builds/slave/test/build/tests 11:31:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:31:17 INFO - https://queue.taskcluster.net/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:31:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:31:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:31:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/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 11:31:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/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 11:31:28 INFO - Downloaded 1199 bytes. 11:31:28 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 11:31:28 INFO - Using the following test package requirements: 11:31:28 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:31:28 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:31:28 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 11:31:28 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:31:28 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 11:31:28 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:31:28 INFO - u'jsshell-mac.zip'], 11:31:28 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:31:28 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 11:31:28 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:31:28 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:31:28 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 11:31:28 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:31:28 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 11:31:28 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:31:28 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 11:31:28 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:31:28 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 11:31:28 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:31: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')]} 11:31:28 INFO - https://queue.taskcluster.net/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:31:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:31:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:31:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/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 11:31:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/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 11:31:40 INFO - Downloaded 18713564 bytes. 11:31:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:31:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:31:43 INFO - Return code: 0 11:31:43 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 11:31:43 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')]} 11:31:43 INFO - https://queue.taskcluster.net/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:31:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:31:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:31:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/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 11:31:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/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 11:31:44 INFO - Downloaded 18713564 bytes. 11:31:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:31:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:31:48 INFO - Return code: 0 11:31:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:31:48 INFO - https://queue.taskcluster.net/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:31:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:31:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:31:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/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 11:31:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/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 11:31:49 INFO - Downloaded 10834055 bytes. 11:31:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:31:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 11:31:49 INFO - Return code: 0 11:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:31:49 INFO - https://queue.taskcluster.net/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 11:31:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:31:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:31:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 11:31:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FtS4x_yTRZSR8NO1zv4apg/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 11:31:54 INFO - Downloaded 111076299 bytes. 11:31:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:31:54 INFO - mkdir: /builds/slave/test/properties 11:31:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:31:54 INFO - Writing to file /builds/slave/test/properties/build_url 11:31:54 INFO - Contents: 11:31:54 INFO - build_url:https://queue.taskcluster.net/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:31:56 INFO - Running post-action listener: _resource_record_post_action 11:31:56 INFO - Running post-action listener: set_extra_try_arguments 11:31:56 INFO - ##### 11:31:56 INFO - ##### Finished download-and-extract step (success) 11:31:56 INFO - ##### 11:31:56 INFO - ##### 11:31:56 INFO - ##### Running populate-webroot step. 11:31:56 INFO - ##### 11:31:56 INFO - Running pre-action listener: _resource_record_pre_action 11:31:56 INFO - Running main action method: populate_webroot 11:31:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:31:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:31:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:31:56 INFO - '--tppagecycles', 11:31:56 INFO - '7'], 11:31:56 INFO - 'tests': ['tsvgm']}}, 11:31:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:31:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:31:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:31:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:31:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:31:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:31:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:31:56 INFO - 'talos_options': ['--disable-e10s'], 11:31:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:31:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:31:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:31:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:31:56 INFO - 'talos_options': ['--disable-e10s'], 11:31:56 INFO - 'tests': ['damp', 'tps']}, 11:31:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:31:56 INFO - 'tests': ['damp', 'tps']}, 11:31:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:31:56 INFO - 'tests': ['dromaeo_dom']}, 11:31:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:31:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:31:56 INFO - 'tests': ['a11yr', 11:31:56 INFO - 'ts_paint', 11:31:56 INFO - 'tpaint', 11:31:56 INFO - 'sessionrestore', 11:31:56 INFO - 'sessionrestore_no_auto_restore', 11:31:56 INFO - 'tabpaint']}, 11:31:56 INFO - 'other-e10s': {'tests': ['a11yr', 11:31:56 INFO - 'ts_paint', 11:31:56 INFO - 'tpaint', 11:31:56 INFO - 'sessionrestore', 11:31:56 INFO - 'sessionrestore_no_auto_restore', 11:31:56 INFO - 'tabpaint']}, 11:31:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:31:56 INFO - 'ts_paint', 11:31:56 INFO - 'tpaint', 11:31:56 INFO - 'sessionrestore', 11:31:56 INFO - 'sessionrestore_no_auto_restore', 11:31:56 INFO - 'tabpaint']}, 11:31:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:31:56 INFO - 'ts_paint', 11:31:56 INFO - 'tpaint', 11:31:56 INFO - 'sessionrestore', 11:31:56 INFO - 'sessionrestore_no_auto_restore', 11:31:56 INFO - 'tabpaint']}, 11:31:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:31:56 INFO - 'tests': ['a11yr', 11:31:56 INFO - 'ts_paint', 11:31:56 INFO - 'tpaint', 11:31:56 INFO - 'sessionrestore', 11:31:56 INFO - 'sessionrestore_no_auto_restore', 11:31:56 INFO - 'tabpaint']}, 11:31:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:31:56 INFO - 'tests': ['a11yr', 11:31:56 INFO - 'ts_paint', 11:31:56 INFO - 'tpaint', 11:31:56 INFO - 'sessionrestore', 11:31:56 INFO - 'sessionrestore_no_auto_restore', 11:31:56 INFO - 'tabpaint']}, 11:31:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:31:56 INFO - 'tests': ['tsvgx', 11:31:56 INFO - 'tsvgr_opacity', 11:31:56 INFO - 'tart', 11:31:56 INFO - 'tscrollx', 11:31:56 INFO - 'cart']}, 11:31:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:31:56 INFO - 'tsvgr_opacity', 11:31:56 INFO - 'tart', 11:31:56 INFO - 'tscrollx', 11:31:56 INFO - 'cart']}, 11:31:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:31:56 INFO - 'talos_options': ['--disable-e10s'], 11:31:56 INFO - 'tests': ['tp5o']}, 11:31:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:31:56 INFO - 'tests': ['tp5o']}, 11:31:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:31:56 INFO - 'talos_options': ['--disable-e10s', 11:31:56 INFO - '--xperf_path', 11:31:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:31:56 INFO - 'tests': ['tp5n']}, 11:31:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:31:56 INFO - 'talos_options': ['--xperf_path', 11:31:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:31:56 INFO - 'tests': ['tp5n']}}, 11:31:56 INFO - 'talos.zip': {'path': '', 11:31:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:31:56 INFO - Running post-action listener: _resource_record_post_action 11:31:56 INFO - ##### 11:31:56 INFO - ##### Finished populate-webroot step (success) 11:31:56 INFO - ##### 11:31:56 INFO - ##### 11:31:56 INFO - ##### Running create-virtualenv step. 11:31:56 INFO - ##### 11:31:56 INFO - Running pre-action listener: _resource_record_pre_action 11:31:56 INFO - Running main action method: create_virtualenv 11:31:56 INFO - Creating virtualenv /builds/slave/test/build/venv 11:31:56 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 11:31:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:31:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:31:56 INFO - Using real prefix '/tools/python27' 11:31:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:31:57 INFO - Installing distribute.............................................................................................................................................................................................done. 11:32:00 INFO - Installing pip.................done. 11:32:00 INFO - Return code: 0 11:32:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:32: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')]} 11:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32: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')]} 11:32:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f8cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c5a580830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f773d30>, '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 0x10f597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f76ab20>, '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.r2btpFwnF0/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.KlVFz3X0iC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 11:32: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', 'pip>=1.5'] in /builds/slave/test/build 11:32: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 pip>=1.5 11:32:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r2btpFwnF0/Render', 11:32:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:00 INFO - 'HOME': '/Users/cltbld', 11:32:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:00 INFO - 'LOGNAME': 'cltbld', 11:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:00 INFO - 'MOZ_NO_REMOTE': '1', 11:32:00 INFO - 'NO_EM_RESTART': '1', 11:32:00 INFO - 'PAGER': '/bin/cat', 11:32:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:00 INFO - 'PWD': '/builds/slave/test', 11:32:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:00 INFO - 'SHELL': '/bin/bash', 11:32:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KlVFz3X0iC/Listeners', 11:32:00 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 11:32:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:00 INFO - 'USER': 'cltbld', 11:32:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:00 INFO - 'XPC_FLAGS': '0x0', 11:32:00 INFO - 'XPC_SERVICE_NAME': '0', 11:32:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:00 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 11:32:00 INFO - Cleaning up... 11:32:00 INFO - Return code: 0 11:32:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:32: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')]} 11:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32: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')]} 11:32:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32: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', '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 0x10f8cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c5a580830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f773d30>, '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 0x10f597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f76ab20>, '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.r2btpFwnF0/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.KlVFz3X0iC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 11:32: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:32: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 psutil>=3.1.1 11:32:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r2btpFwnF0/Render', 11:32:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:00 INFO - 'HOME': '/Users/cltbld', 11:32:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:00 INFO - 'LOGNAME': 'cltbld', 11:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:00 INFO - 'MOZ_NO_REMOTE': '1', 11:32:00 INFO - 'NO_EM_RESTART': '1', 11:32:00 INFO - 'PAGER': '/bin/cat', 11:32:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:00 INFO - 'PWD': '/builds/slave/test', 11:32:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:00 INFO - 'SHELL': '/bin/bash', 11:32:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KlVFz3X0iC/Listeners', 11:32:00 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 11:32:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:00 INFO - 'USER': 'cltbld', 11:32:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:00 INFO - 'XPC_FLAGS': '0x0', 11:32:00 INFO - 'XPC_SERVICE_NAME': '0', 11:32:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:00 INFO - Downloading/unpacking psutil>=3.1.1 11:32: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 11:32: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 11:32: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 11:32: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 11:32: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 11:32: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 11:32:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:32:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:32:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:32:03 INFO - Installing collected packages: psutil 11:32:03 INFO - Running setup.py install for psutil 11:32:04 INFO - building 'psutil._psutil_osx' extension 11:32:04 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 11:32:04 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 11:32:04 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 11:32:04 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 11:32:04 INFO - building 'psutil._psutil_posix' extension 11:32:04 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 11:32:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:32:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:32:04 INFO - ^ 11:32:04 INFO - 1 warning generated. 11:32:04 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 11:32:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:32:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:32:04 INFO - Successfully installed psutil 11:32:04 INFO - Cleaning up... 11:32:04 INFO - Return code: 0 11:32:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:32:04 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')]} 11:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:04 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')]} 11:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:04 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 0x10f8cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c5a580830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f773d30>, '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 0x10f597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f76ab20>, '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.r2btpFwnF0/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.KlVFz3X0iC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 11:32:04 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 11:32:04 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 11:32:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r2btpFwnF0/Render', 11:32:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:04 INFO - 'HOME': '/Users/cltbld', 11:32:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:04 INFO - 'LOGNAME': 'cltbld', 11:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:04 INFO - 'MOZ_NO_REMOTE': '1', 11:32:04 INFO - 'NO_EM_RESTART': '1', 11:32:04 INFO - 'PAGER': '/bin/cat', 11:32:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:04 INFO - 'PWD': '/builds/slave/test', 11:32:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:04 INFO - 'SHELL': '/bin/bash', 11:32:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KlVFz3X0iC/Listeners', 11:32:04 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 11:32:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:04 INFO - 'USER': 'cltbld', 11:32:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:04 INFO - 'XPC_FLAGS': '0x0', 11:32:04 INFO - 'XPC_SERVICE_NAME': '0', 11:32:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:32:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:32:07 INFO - Installing collected packages: mozsystemmonitor 11:32:07 INFO - Running setup.py install for mozsystemmonitor 11:32:07 INFO - Successfully installed mozsystemmonitor 11:32:07 INFO - Cleaning up... 11:32:07 INFO - Return code: 0 11:32:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:32:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:07 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 0x10f8cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c5a580830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f773d30>, '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 0x10f597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f76ab20>, '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.r2btpFwnF0/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.KlVFz3X0iC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 11:32:07 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 11:32:07 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 11:32:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r2btpFwnF0/Render', 11:32:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:07 INFO - 'HOME': '/Users/cltbld', 11:32:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:07 INFO - 'LOGNAME': 'cltbld', 11:32:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:07 INFO - 'MOZ_NO_REMOTE': '1', 11:32:07 INFO - 'NO_EM_RESTART': '1', 11:32:07 INFO - 'PAGER': '/bin/cat', 11:32:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:07 INFO - 'PWD': '/builds/slave/test', 11:32:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:07 INFO - 'SHELL': '/bin/bash', 11:32:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KlVFz3X0iC/Listeners', 11:32:07 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 11:32:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:07 INFO - 'USER': 'cltbld', 11:32:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:07 INFO - 'XPC_FLAGS': '0x0', 11:32:07 INFO - 'XPC_SERVICE_NAME': '0', 11:32:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:08 INFO - Downloading/unpacking blobuploader==1.2.4 11:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:10 INFO - Downloading blobuploader-1.2.4.tar.gz 11:32:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:32:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:32:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:32:11 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 11:32:11 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 11:32:11 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 11:32:11 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 11:32:11 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 11:32:11 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 11:32:11 INFO - Downloading docopt-0.6.1.tar.gz 11:32:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:32:11 INFO - Installing collected packages: blobuploader, requests, docopt 11:32:11 INFO - Running setup.py install for blobuploader 11:32:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:32:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:32:11 INFO - Running setup.py install for requests 11:32:12 INFO - Running setup.py install for docopt 11:32:12 INFO - Successfully installed blobuploader requests docopt 11:32:12 INFO - Cleaning up... 11:32:12 INFO - Return code: 0 11:32:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:32:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:32:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:12 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 0x10f8cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c5a580830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f773d30>, '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 0x10f597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f76ab20>, '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.r2btpFwnF0/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.KlVFz3X0iC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 11:32:12 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 11:32:12 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 11:32:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r2btpFwnF0/Render', 11:32:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:12 INFO - 'HOME': '/Users/cltbld', 11:32:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:12 INFO - 'LOGNAME': 'cltbld', 11:32:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:12 INFO - 'MOZ_NO_REMOTE': '1', 11:32:12 INFO - 'NO_EM_RESTART': '1', 11:32:12 INFO - 'PAGER': '/bin/cat', 11:32:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:12 INFO - 'PWD': '/builds/slave/test', 11:32:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:12 INFO - 'SHELL': '/bin/bash', 11:32:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KlVFz3X0iC/Listeners', 11:32:12 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 11:32:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:12 INFO - 'USER': 'cltbld', 11:32:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:12 INFO - 'XPC_FLAGS': '0x0', 11:32:12 INFO - 'XPC_SERVICE_NAME': '0', 11:32:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:32:12 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-yhtB2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:32:13 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-2Mp7D9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:32:13 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-q1c56L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:32:13 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-LV5Sdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:32:13 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-hJPiMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:32:13 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-RDhAex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:32:13 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-6_cXWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:32:13 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Fwb7P9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:32:13 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-0UbmyI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:32:13 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-LVXOXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:32:13 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-NM0ZIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:32:14 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-AbGYk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:32:14 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-tTidT5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:32:14 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-hs39n7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:32:14 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-QAcuhS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:32:14 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-KJ0k5h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:32:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:32:14 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-VKZL3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:32:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:32:14 INFO - Running setup.py install for manifestparser 11:32:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:32:14 INFO - Running setup.py install for mozcrash 11:32:14 INFO - Running setup.py install for mozdebug 11:32:15 INFO - Running setup.py install for mozdevice 11:32:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:32:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:32:15 INFO - Running setup.py install for mozfile 11:32:15 INFO - Running setup.py install for mozhttpd 11:32:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:32:15 INFO - Running setup.py install for mozinfo 11:32:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:32:15 INFO - Running setup.py install for mozInstall 11:32:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:32:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:32:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:32:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:32:15 INFO - Running setup.py install for mozleak 11:32:15 INFO - Running setup.py install for mozlog 11:32:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:32:16 INFO - Running setup.py install for moznetwork 11:32:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:32:16 INFO - Running setup.py install for mozprocess 11:32:16 INFO - Running setup.py install for mozprofile 11:32:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:32:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:32:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:32:16 INFO - Running setup.py install for mozrunner 11:32:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:32:16 INFO - Running setup.py install for mozscreenshot 11:32:16 INFO - Running setup.py install for moztest 11:32:17 INFO - Running setup.py install for mozversion 11:32:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:32:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:32:17 INFO - Cleaning up... 11:32:17 INFO - Return code: 0 11:32:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:32:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:32:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:17 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 0x10f8cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c5a580830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f773d30>, '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 0x10f597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f76ab20>, '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.r2btpFwnF0/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.KlVFz3X0iC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 11:32:17 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 11:32:17 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 11:32:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r2btpFwnF0/Render', 11:32:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:17 INFO - 'HOME': '/Users/cltbld', 11:32:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:17 INFO - 'LOGNAME': 'cltbld', 11:32:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:17 INFO - 'MOZ_NO_REMOTE': '1', 11:32:17 INFO - 'NO_EM_RESTART': '1', 11:32:17 INFO - 'PAGER': '/bin/cat', 11:32:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:17 INFO - 'PWD': '/builds/slave/test', 11:32:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:17 INFO - 'SHELL': '/bin/bash', 11:32:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KlVFz3X0iC/Listeners', 11:32:17 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 11:32:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:17 INFO - 'USER': 'cltbld', 11:32:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:17 INFO - 'XPC_FLAGS': '0x0', 11:32:17 INFO - 'XPC_SERVICE_NAME': '0', 11:32:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:32:17 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-iKIstQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:32:17 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)) 11:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:32:17 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-AQAv3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:32:17 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)) 11:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:32:17 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-QtnJha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:32:17 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)) 11:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:32:17 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-tCouwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:32:17 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)) 11:32:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:32:17 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-TvX3Hh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:32:18 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)) 11:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:32:18 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-StSmMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:32:18 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)) 11:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:32:18 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-ZsozXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:32:18 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)) 11:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:32:18 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-pe4sQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:32:18 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)) 11:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:32:18 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-no7Fsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:32:18 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)) 11:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:32:18 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-7iuSKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:32:18 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)) 11:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:32:18 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-NstK0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:32:18 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)) 11:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:32:18 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-hKFFH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:32:18 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)) 11:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:32:18 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-ApisLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:32:18 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)) 11:32:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:32:18 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-0FA6bt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:32:19 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)) 11:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:32:19 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-PEHI3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:32:19 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)) 11:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:32:19 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-UQvjNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:32:19 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)) 11:32:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:32:19 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-Tq2qf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:32:19 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)) 11:32:19 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)) 11:32:19 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)) 11:32:19 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)) 11:32:19 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)) 11:32:19 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)) 11:32:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:21 INFO - Downloading blessings-1.6.tar.gz 11:32:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:32:21 INFO - Installing collected packages: blessings 11:32:21 INFO - Running setup.py install for blessings 11:32:21 INFO - Successfully installed blessings 11:32:21 INFO - Cleaning up... 11:32:22 INFO - Return code: 0 11:32:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:32:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:32:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:32:22 INFO - Reading from file tmpfile_stdout 11:32:22 INFO - Current package versions: 11:32:22 INFO - blessings == 1.6 11:32:22 INFO - blobuploader == 1.2.4 11:32:22 INFO - docopt == 0.6.1 11:32:22 INFO - manifestparser == 1.1 11:32:22 INFO - mozInstall == 1.12 11:32:22 INFO - mozcrash == 0.17 11:32:22 INFO - mozdebug == 0.1 11:32:22 INFO - mozdevice == 0.48 11:32:22 INFO - mozfile == 1.2 11:32:22 INFO - mozhttpd == 0.7 11:32:22 INFO - mozinfo == 0.9 11:32:22 INFO - mozleak == 0.1 11:32:22 INFO - mozlog == 3.2 11:32:22 INFO - moznetwork == 0.27 11:32:22 INFO - mozprocess == 0.22 11:32:22 INFO - mozprofile == 0.28 11:32:22 INFO - mozrunner == 6.11 11:32:22 INFO - mozscreenshot == 0.1 11:32:22 INFO - mozsystemmonitor == 0.3 11:32:22 INFO - moztest == 0.7 11:32:22 INFO - mozversion == 1.4 11:32:22 INFO - psutil == 3.1.1 11:32:22 INFO - requests == 1.2.3 11:32:22 INFO - wsgiref == 0.1.2 11:32:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:32:22 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')]} 11:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:22 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')]} 11:32:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:22 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 0x10f8cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c5a580830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f773d30>, '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 0x10f597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f76ab20>, '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.r2btpFwnF0/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.KlVFz3X0iC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 11:32:22 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 11:32:22 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 11:32:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r2btpFwnF0/Render', 11:32:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:22 INFO - 'HOME': '/Users/cltbld', 11:32:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:22 INFO - 'LOGNAME': 'cltbld', 11:32:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:22 INFO - 'MOZ_NO_REMOTE': '1', 11:32:22 INFO - 'NO_EM_RESTART': '1', 11:32:22 INFO - 'PAGER': '/bin/cat', 11:32:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:22 INFO - 'PWD': '/builds/slave/test', 11:32:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:22 INFO - 'SHELL': '/bin/bash', 11:32:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KlVFz3X0iC/Listeners', 11:32:22 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 11:32:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:22 INFO - 'USER': 'cltbld', 11:32:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:22 INFO - 'XPC_FLAGS': '0x0', 11:32:22 INFO - 'XPC_SERVICE_NAME': '0', 11:32:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:22 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)) 11:32:22 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)) 11:32:22 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)) 11:32:22 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)) 11:32:22 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)) 11:32:22 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)) 11:32:22 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)) 11:32:22 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)) 11:32:22 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)) 11:32:22 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)) 11:32:22 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)) 11:32:22 INFO - Cleaning up... 11:32:22 INFO - Return code: 0 11:32:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:32:22 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')]} 11:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:22 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')]} 11:32:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:22 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 0x10f8cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c5a580830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f773d30>, '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 0x10f597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f76ab20>, '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.r2btpFwnF0/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.KlVFz3X0iC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 11:32:22 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 11:32:22 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 11:32:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r2btpFwnF0/Render', 11:32:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:22 INFO - 'HOME': '/Users/cltbld', 11:32:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:22 INFO - 'LOGNAME': 'cltbld', 11:32:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:22 INFO - 'MOZ_NO_REMOTE': '1', 11:32:22 INFO - 'NO_EM_RESTART': '1', 11:32:22 INFO - 'PAGER': '/bin/cat', 11:32:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:22 INFO - 'PWD': '/builds/slave/test', 11:32:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:22 INFO - 'SHELL': '/bin/bash', 11:32:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KlVFz3X0iC/Listeners', 11:32:22 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 11:32:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:22 INFO - 'USER': 'cltbld', 11:32:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:22 INFO - 'XPC_FLAGS': '0x0', 11:32:22 INFO - 'XPC_SERVICE_NAME': '0', 11:32:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:22 INFO - Downloading/unpacking jsonschema 11:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:32:25 INFO - Downloading/unpacking functools32 (from jsonschema) 11:32:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:25 INFO - Downloading functools32-3.2.3-2.tar.gz 11:32:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:32:25 INFO - warning: no files found matching '*.txt' 11:32:25 INFO - no previously-included directories found matching 'build' 11:32:25 INFO - no previously-included directories found matching 'dist' 11:32:25 INFO - no previously-included directories found matching '.git*' 11:32:25 INFO - Installing collected packages: jsonschema, functools32 11:32:25 INFO - Running setup.py install for functools32 11:32:26 INFO - warning: no files found matching '*.txt' 11:32:26 INFO - no previously-included directories found matching 'build' 11:32:26 INFO - no previously-included directories found matching 'dist' 11:32:26 INFO - no previously-included directories found matching '.git*' 11:32:26 INFO - Successfully installed jsonschema functools32 11:32:26 INFO - Cleaning up... 11:32:26 INFO - Return code: 0 11:32:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:32:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:32:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:32:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:32:26 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 0x10f8cfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8d5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c5a580830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f773d30>, '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 0x10f597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f76ab20>, '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.r2btpFwnF0/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.KlVFz3X0iC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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 11:32:26 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 11:32:26 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 11:32:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r2btpFwnF0/Render', 11:32:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:32:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:32:26 INFO - 'HOME': '/Users/cltbld', 11:32:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:32:26 INFO - 'LOGNAME': 'cltbld', 11:32:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:32:26 INFO - 'MOZ_NO_REMOTE': '1', 11:32:26 INFO - 'NO_EM_RESTART': '1', 11:32:26 INFO - 'PAGER': '/bin/cat', 11:32:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:32:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:32:26 INFO - 'PWD': '/builds/slave/test', 11:32:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:32:26 INFO - 'SHELL': '/bin/bash', 11:32:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KlVFz3X0iC/Listeners', 11:32:26 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 11:32:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:32:26 INFO - 'USER': 'cltbld', 11:32:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:32:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:32:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:32:26 INFO - 'XPC_FLAGS': '0x0', 11:32:26 INFO - 'XPC_SERVICE_NAME': '0', 11:32:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:32:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:32:26 INFO - Downloading/unpacking flake8 11:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:32:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:32:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:32:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:32:28 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 11:32:28 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 11:32:28 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 11:32:28 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 11:32:28 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 11:32:28 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 11:32:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:32:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:32:29 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 11:32:29 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 11:32:29 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 11:32:29 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 11:32:29 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 11:32:29 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 11:32:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:32:29 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 11:32:29 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 11:32:29 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 11:32:29 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 11:32:29 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 11:32:29 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 11:32:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:32:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:32:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:32:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:32:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:32:30 INFO - no previously-included directories found matching 'docs/_build' 11:32:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:32:30 INFO - Running setup.py install for pep8 11:32:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:32:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:32:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:32:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:32:30 INFO - no previously-included directories found matching 'docs/_build' 11:32:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:32:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:32:30 INFO - Cleaning up... 11:32:30 INFO - Return code: 0 11:32:30 INFO - Running post-action listener: _resource_record_post_action 11:32:30 INFO - Running post-action listener: _start_resource_monitoring 11:32:30 INFO - Starting resource monitoring. 11:32:30 INFO - ##### 11:32:30 INFO - ##### Finished create-virtualenv step (success) 11:32:30 INFO - ##### 11:32:30 INFO - ##### 11:32:30 INFO - ##### Running install step. 11:32:30 INFO - ##### 11:32:30 INFO - Running pre-action listener: _resource_record_pre_action 11:32:30 INFO - Running main action method: install 11:32:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:32:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:32:30 INFO - Reading from file tmpfile_stdout 11:32:30 INFO - Detecting whether we're running mozinstall >=1.0... 11:32:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:32:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:32:30 INFO - Reading from file tmpfile_stdout 11:32:30 INFO - Output received: 11:32:30 INFO - Usage: mozinstall [options] installer 11:32:30 INFO - Options: 11:32:30 INFO - -h, --help show this help message and exit 11:32:30 INFO - -d DEST, --destination=DEST 11:32:30 INFO - Directory to install application into. [default: 11:32:30 INFO - "/builds/slave/test"] 11:32:30 INFO - --app=APP Application being installed. [default: firefox] 11:32:30 INFO - mkdir: /builds/slave/test/build/application 11:32:30 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'] 11:32:30 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 11:33:05 INFO - Reading from file tmpfile_stdout 11:33:05 INFO - Output received: 11:33:05 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:33:05 INFO - Running post-action listener: _resource_record_post_action 11:33:05 INFO - ##### 11:33:05 INFO - ##### Finished install step (success) 11:33:05 INFO - ##### 11:33:05 INFO - ##### 11:33:05 INFO - ##### Running run-tests step. 11:33:05 INFO - ##### 11:33:05 INFO - Running pre-action listener: _resource_record_pre_action 11:33:05 INFO - Running main action method: run_tests 11:33:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:33:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:33:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:33:05 INFO - 2016-06-02 11:33:05.104 screenresolution[1939:17734] starting screenresolution argv=screenresolution get 11:33:05 INFO - 2016-06-02 11:33:05.107 screenresolution[1939:17734] Display 0: 1600x1200x32@60 11:33:05 INFO - 2016-06-02 11:33:05.113 screenresolution[1940:17736] starting screenresolution argv=screenresolution list 11:33:05 INFO - Available Modes on Display 0 11:33:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:33:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:33:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:33:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:33:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:33:05 INFO - Intel Iris: 11:33:05 INFO - Chipset Model: Intel Iris 11:33:05 INFO - Type: GPU 11:33:05 INFO - Bus: Built-In 11:33:05 INFO - VRAM (Dynamic, Max): 1536 MB 11:33:05 INFO - Vendor: Intel (0x8086) 11:33:05 INFO - Device ID: 0x0a2e 11:33:05 INFO - Revision ID: 0x0009 11:33:05 INFO - Displays: 11:33:05 INFO - Display: 11:33:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:33:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:33:05 INFO - Display Serial Number: 1600x1200 60 11:33:05 INFO - Main Display: Yes 11:33:05 INFO - Mirror: Off 11:33:05 INFO - Online: Yes 11:33:05 INFO - Rotation: Supported 11:33:05 INFO - Return code: 0 11:33:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:33:05 INFO - Python 2.7.3 11:33:05 INFO - Return code: 0 11:33:05 INFO - grabbing minidump binary from tooltool 11:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:33:05 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 0x10f773d30>, '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 0x10f597db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f76ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:33:05 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 11:33:05 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 11:33:05 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 11:33:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:33:05 INFO - Return code: 0 11:33:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:33:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:33:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:33:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:33:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:33:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:33:06 INFO - Return code: 0 11:33:06 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/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--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 11:33:06 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/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0110 --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 11:33:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r2btpFwnF0/Render', 11:33:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:33:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:33:06 INFO - 'HOME': '/Users/cltbld', 11:33:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:33:06 INFO - 'LOGNAME': 'cltbld', 11:33:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:33:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:33:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:33:06 INFO - 'MOZ_NO_REMOTE': '1', 11:33:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:33:06 INFO - 'NO_EM_RESTART': '1', 11:33:06 INFO - 'PAGER': '/bin/cat', 11:33:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:33:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:33:06 INFO - 'PWD': '/builds/slave/test', 11:33:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:33:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:33:06 INFO - 'SHELL': '/bin/bash', 11:33:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KlVFz3X0iC/Listeners', 11:33:06 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 11:33:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:33:06 INFO - 'USER': 'cltbld', 11:33:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:33:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:33:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:33:06 INFO - 'XPC_FLAGS': '0x0', 11:33:06 INFO - 'XPC_SERVICE_NAME': '0', 11:33:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:33:06 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/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--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 11:33:06 INFO - mozversion application_buildid: 20160602100536 11:33:06 INFO - mozversion application_changeset: 91879ebba4d61fde72af13223708faa01109a4d9 11:33:06 INFO - mozversion application_display_name: Nightly 11:33:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:33:06 INFO - mozversion application_name: Firefox 11:33:06 INFO - mozversion application_remotingname: firefox 11:33:06 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:33:06 INFO - mozversion application_vendor: Mozilla 11:33:06 INFO - mozversion application_version: 49.0a1 11:33:06 INFO - mozversion platform_buildid: 20160602100536 11:33:06 INFO - mozversion platform_changeset: 91879ebba4d61fde72af13223708faa01109a4d9 11:33:06 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:33:06 INFO - mozversion platform_version: 49.0a1 11:33:06 INFO - using testdate: 1464892386 11:33:06 INFO - actual date: 1464892386 11:33:06 INFO - starting webserver on 'localhost:53221' 11:33:06 INFO - SUITE-START | Running 2 tests 11:33:06 INFO - TEST-START | dromaeo_css 11:33:06 INFO - operating with platform_type : mac_ 11:33:06 INFO - Initialising browser for dromaeo_css test... 11:33:06 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpASvwPH/profile http://localhost:53221/getInfo.html) 11:33:09 INFO - PROCESS | 1958 | __metrics Screen width/height:1600/1200 11:33:09 INFO - PROCESS | 1958 | colorDepth:24 11:33:09 INFO - PROCESS | 1958 | Browser inner width/height: 1024/689 11:33:09 INFO - PROCESS | 1958 | __metrics 11:33:09 INFO - PROCESS | 1958 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:33:09 ERROR - PROCESS | 1958 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:33:10 INFO - TEST-INFO | 1958: exit 0 11:33:10 INFO - Browser initialized. 11:33:10 INFO - Running cycle 1/1 for dromaeo_css test... 11:33:10 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpASvwPH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:33:21 INFO - PROCESS | 1960 | RSS: Main: 241954816 11:33:21 INFO - PROCESS | 1960 | 11:34:12 INFO - PROCESS | 1960 | 1464892452961 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] 11:36:24 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53221/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53221/tests/dromaeo/cssquery-ext.html) 11:36:25 INFO - PROCESS | 1960 | RSS: Main: 359071744 11:36:25 INFO - PROCESS | 1960 | 11:39:28 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53221/tests/dromaeo/cssquery-ext.html (next: http://localhost:53221/tests/dromaeo/cssquery-jquery.html) 11:39:29 INFO - PROCESS | 1960 | RSS: Main: 380223488 11:39:29 INFO - PROCESS | 1960 | 11:42:31 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53221/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53221/tests/dromaeo/cssquery-mootools.html) 11:42:31 INFO - PROCESS | 1960 | RSS: Main: 374038528 11:42:31 INFO - PROCESS | 1960 | 11:45:34 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53221/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53221/tests/dromaeo/cssquery-prototype.html) 11:45:34 INFO - PROCESS | 1960 | RSS: Main: 372326400 11:45:34 INFO - PROCESS | 1960 | 11:48:38 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53221/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53221/tests/dromaeo/cssquery-yui.html) 11:48:38 INFO - PROCESS | 1960 | RSS: Main: 350564352 11:48:38 INFO - PROCESS | 1960 | 11:51:41 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53221/tests/dromaeo/cssquery-yui.html (next: http://localhost:53221/tests/dromaeo/cssquery-dojo.html) 11:51:41 INFO - PROCESS | 1960 | RSS: Main: 406077440 11:51:41 INFO - PROCESS | 1960 | 11:51:41 INFO - PROCESS | 1960 | __start_tp_report 11:51:41 INFO - PROCESS | 1960 | _x_x_mozilla_page_load 11:51:41 INFO - PROCESS | 1960 | _x_x_mozilla_page_load_details 11:51:41 INFO - PROCESS | 1960 | |i|pagename|runs| 11:51:41 INFO - PROCESS | 1960 | |0;dojo.html;3443.362977849098;3454.481827725841;3465.3069386122775;3484;3532.964670353296;5607.4112218217115;5615.887682246353;5703.914441283374;5738.7991420300295;5748.2527271454655;5664.688442135681;5691.800786972457;5754.971225143879;5796.159556864262;5811.535077193815;5085.1355269604255;5112.156494178456;5167.612429067816;5196.324477817879;5201.661891977028;5199.532042116209;5203.869903252411;5221.869453263661;5238.030964271603;5266.262723218733;5119.2321151827155;5138.126518491866;5167.121589329823;5195.376554813428;5206.635535512534;5176.534111929906;5184.377874655065;5184.585233181337;5188.066148093341;5191.896162076738;5123.871903202413;5129.204973229155;5146.536811686929;5152.974235128838;5163.793448262065;5563.443655634444;5570.2480165177585;5597.496225339698;5657.57568182388;5677.517411020068;6125.6170790510105;6172;6184.6907654617535;6201.348858369851;6210.7826226082325;6121.143039974407;6176.011838105882;6229.221347331583;6279.654618995954;6294.874102517924;5743.827685169452;5763.135529670499;5781.219535362672;5827.854303642443;5832.766689332379;6309.968450157811;6315.842103947346;6333.113364129025;6336.714847831859;6349.555531112777;5908.14331921869;6025.487833534111;6032.155498230251;6093.634381937098;6122.908156377658;6172.351903050115;6199.225096862892;6210.689465526705;6217.098520714471;6222.564420490522;5525.11598144303;5888.322842873039;6201.224846894138;6213.316535181127;6214.40963108504;6171.56799024073;6175.567710260329;6259.311475737665;6263.874722505616;6269.843253918597;4944.53026962438;4945.727984960861;4948.455669876311;4950;4975.0796102721115;4339.1104823511105;4368.847090351823;4404.955950440519;4445.688801783909;4484.215262329145;4565.474970378383;5684.118961560965;5684.77260909559;5708.65748055118;5801.8549536261935;6258.812235632939;6298.622831633916;6301.306856245809;6322.778702745474;6346.936530634727;5448.509634132868;5584.609077364546;5586.050371436865;5647.548196144381;5653.491185793298;5382.784688612412;5387.649802762886;5403.13549832025;5406.648567843077;5410.377806552218;6329.810105696836;6332.4934005279465;6348.555601107971;6373.553851230555;6375;6129.358790882775;6191.473724733448;6242.750289988531;6243.937560624336;6345.555811093179;6251.218597675291;6283.120363149072;6283.717232724447;6288.402601752826;6291.559590828781;5948.315943666534;6175.44421002112;6188.381161883775;6212.223472065992;6219.40915613016;6127.601705889103;6167;6193.194884664965;6252.968735156295;6293.748250069946;6061.969690151521;6096.9390306096375;6102;6118.694065296629;6124.969375153096;4889.193283108248;5942.286925568959;6088.730290291695;6095.420935011167;6141.447269745744;5783.479486846204;5916.585838991229;5944.256967879015;5950.285965684146;5960.195373624505;6098.573099882966;6102.298235702951;6104.725287361991;6110.358412366815;6121.877562448637;5738.311402631711;5785.710714464344;5834.679092649945;5923.289205295392;5932.466078052996 11:51:41 INFO - PROCESS | 1960 | |1;ext.html;482.2284345047924;502.8441183233198;504.2486694824712;510.8824970256841;563.5857644631196;31017.224569385748;41504.79247603761;42798.14403711924;42860.142797144115;44764.328535071916;44140.896477587994;44494.777526112324;44662.553374466246;44683.77658111705;44700.32949505752;8463;9484.478353690562;9736.951315243414;9743.51282435879;9744.853827192597;8611.741647750547;11484.36835974021;11843.052555795559;11844.94077529611;11855.466504007298;11339.829902551468;11617.6514704559;11673.124515661317;11698.122640801932;11727.237729547553;8584.141585841366;8834.69078582253;8848.823023539493;11585.710357241094;11710.590129345517;8630.568471576396;9118.81762364749;11527.596534121349;11531;11793.941030294794;41280.967975800544;41673;41755.37366939468;41974.79012604948;42007.94980125491;3487.2851065531504;3508;3519.2257703305486;3618.8009659468717;3851.5763266040713;31735.84132079325;32420.513692294633;32444.513332300034;32674.6732532674;33007;3236.433624115794;3260.739140868725;3272.083816531351;3298.934021319584;3396.235846934445;512700;514594;515604;516288.41855790856;516317;32803.50794738081;33701.325973480394;61408.385916140265;61834;61944.69027654833;36055.81972090175;36244.4563331545;36407.817960910026;36418;36459.27081458356;14973.176475293963;15343.923280383528;15349.9232503839;15350.539483815503;15356.385744570092;11655.59205427806;11901.404929753478;12597.181183223061;12778.041646876522;12910.096293259381;7961.16407777175;7967;7998.5600791957;8037.557934313669;8066.5563394012715;3208.6149662040702;3220.3559288142465;3222.3394204188085;3243.2540515681053;3258.1365938026443;853.7737499562621;939.2767568971854;1024.666983230453;1072.0780129088978;1098.5441041967654;24978.625320620205;29238.26904327409;30547;30671.23321916925;31510.684893150774;1087.6410784441273;1092.2900114925362;1177.6879127031343;1180.7992641251008;1197.4379009500994;12988.675283117998;16025;16045.117518536592;16327;16461.423850165185;181382;181860;181979;182433;183353;59297;59901.801963960475;59937.10094348501;60032;60054.39945600488;1259.7480503899258;1310.3382791690135;1310.652677040604;1384.3286006286942;1425.9001869869217;22312.330630081124;25709.871450642626;30120.397592048474;30540;32224.19439513937;56215.71892140513;57172.71413642905;57188.1421778682;57289.713551431974;57510.424895750504;592703;619614;620872;621325;623441;23801.928913199295;24034.63948040746;26018.86990565035;26935;27244.863775680995;47187.29219061785;47942.28086578774;48194.518054819004;48240.552783416555;48249.517504825904;37710.62289377071;40039.85051101878;41044;42498.78750606227;43609.781951090044;39388.40917386299;39947;40525.79737101296;40987.59012409838;41305.793471032455;38550.6144938547;38806.2238755229;38998.2200355997;39005.41491877567;39311.803440982614;33225.66774332226;34910.65089349074;35413.82293088518;36045;36538.8173059133;25634.35914102088;25843.612345815203;25910.870445648405;26162.73837261679;26192.607110893732 11:51:41 INFO - PROCESS | 1960 | |2;jquery.html;379.4460088271124;379.70383101181073;385.90645746823475;394.64087680211014;403.8707613563659;19245.51886202844;20754.27360042399;21007.2647457339;21315.25396611117;21323.57352852942;20523.897380513114;20747.792522074775;20865.895670521662;21043.894780526116;21218.89390553045;5206.687598744069;5845.357010728827;6010.759569617232;6054.939450605481;6064.3026052003925;5938.554608404365;5971.581989260753;5985.730642121093;6000.639961602296;6041.939580604182;5700.258966334371;5886;5887;5887.499562537189;5901.763929442817;5830;5883.8234852954265;5887.264091988502;5939.287285525743;5955.047192449188;4967.279744437036;5104.259882317043;5145.408278048035;5158.200478925773;5158.3552055993005;24706;25030.749692503025;25390.87304563484;25482.36294092644;26005.609915851277;2234.8100411465043;2235.508188198594;2265.456290490287;2606.2181345596437;2756;14471.638209044753;15285.929984901062;15463.912371829772;15545.027454556232;15996.840031599651;1826.205600563759;1843.4377514857963;1852.2590963614527;1875.990620046891;1905.7903630600622;114377;114998.27502587468;115272.42363788211;115897;116800;9682.612695492102;10488.632897848538;10616.787664246825;10900.073493752961;11016.228863979604;12776.872231277754;13491.662708432172;13648.317584120754;13655.317234138254;13673.863261367458;7032.859342813115;7143.714251429885;7272.49092563526;8587.914120858712;8708.042115367305;8626.929244088722;8635.438696484709;8689.956550217208;8737.60680769367;8814.603342849587;6516.934830651633;6645.843224468339;6662.800115996528;6680.699368528428;6737.9556168793915;1368.4047439363908;1385.4873696732272;1798.9190486428142;1840.1811194018533;1842.9170687319104;830.7258604660568;941.580996456584;959.5681943125621;966.9854952175723;1085.5332207151;16966.660666786596;17943.371981980566;20813.479663008242;20933.05801238948;21815.34553963352;965.8551217317458;967.9274054445945;1031.7162780235494;1035.2080658296409;1073.897979691928;9245.72262832116;11724.355160466086;11743.941280293715;11753.118516111326;11832.704182395337;570109;578579;582623;588854;591534;123556;124023.13965290527;125163;126862.73137268693;127721.72278277283;900.3607438718583;1014.4826138669332;1032.210359075308;1053.6030530543076;1055.1242468750918;18945.43163705091;19253.037348132817;19255.22979080821;19351.90324048371;19413.902930485256;43379.132417352106;49943.00113997627;50747;51156.72108197176;51692;73962;74322;74408.62795685987;75601.91739314141;76396;7090.503664743418;7879.645415956181;7898.644560994884;7995.840083198187;8201;12667;12697;13002.349882506027;13016.414261358072;13237.801432978706;11102.555897764321;11108.555657773599;11315.717107072389;11398.829017564578;11449.885501144881;10901.509432075418;11062.949019843101;11152.442377881236;11239.269447485887;11427.428628568705;5878.529717622666;8004.799880003046;9334.39326443779;11494.873448411736;11668.008219301455;7402.4078073755045;7487.737929172671;7524.17234104259;7531.774046778605;7659.540427574363;10020.697309349738;10798.406087665255;10956.452177391258;11351.545938162712;11423 11:51:41 INFO - PROCESS | 1960 | |3;mootools.html;1367.4940272099311;1530.3190080414213;1558.8363221861705;1761.7005109131446;1906.142235993803;7646.732364367248;8350.916490835098;8580.056193818675;8589.570521473925;8694.782630434242;6626.469882409408;7250.601216933067;8519.53142577158;8578.39951203416;8591.398602097855;2289.713785776778;2318.038014224095;2324.8140148788143;2325.6627788970673;2326.150954901457;2267.85258958168;2306.4695120122383;2311.6070268054473;2314.8148148148107;2317.281642690763;2234.832387570939;2241.529278851435;2249.0216755711317;2306.261996161221;2309.4111001694546;2246.0454306919655;2247.7302723673183;2253.0199363276947;2254.6054440473017;2256.4020534558354;2266.1162146762777;2271.704678391815;2274.5223503064376;2274.886255687209;2279.908803647852;8289.792755181168;8470.534120623362;8538.914610853873;8545.01732300787;8556.443831151017;5142.434332223453;5284.841454756363;5285.973570132163;5293.206019097128;5299.470052994709;3450;3497.9475307870403;3554.289142171576;3570.2680950405106;3571.6964058055096;1043.1811028342715;1044.0655613226195;1050.2018465965843;1062.5324857062867;1065.7122576904192;11203.159763017806;11230.606928757536;11239.66281011571;11254;11290.491927863266;4326.610605045498;4367.912641747147;4484.529124441952;4485.865424037284;4487.88780280489;8924.598393072198;8933;8953.373263871466;8960.01439841617;8963.865542016874;5099.184130539096;5107.642465027487;5111.207762796773;5111.948880511147;5141.794328226829;4285.842822437987;4437.779610607109;4612.86161415158;4641.953580464152;4759.881002974953;3655.5796083450214;3681.815909204529;3723.3484140275345;3753.418220175877;3825.387937929918;1124.9943750281195;1127.0138628699888;1129.203911242572;1135.5798354608683;1137.6075254037344;646.689588997284;724.619574723264;817.6647574494428;861.7630151708205;977.3402953006693;5578.776848926079;5682.602217844711;5865.328381411277;5906.49794767441;5995.190649262294;927.2303987690201;956.4744232948436;957.1146689312498;979.9608015679293;987.1461186074138;3941.3299739044432;4012.7191096622955;4020.3567429211184;4039.6566291864933;4058.188362327487;9897;10503.474826258656;10516.947415263028;10524.526396312185;10538.367697938149;10090.394576325443;10324.22568307365;10476.580936762444;10486.26596138263;10501.842472362921;7811.570363629941;7966.163552827102;8020.799480013046;8034.598270086472;8036.316913062456;5050.444451110301;5134.10153223177;5598.776048958159;5673.489385955284;5764.452377024176;9326.020767819282;9522.14300712939;9744.464054476788;9750.31747777677;9751.75620609462;9663.613455461702;9755.024497550188;9788.853167202355;9796.412215267106;9807.950960245153;7267.890981635385;7317.341439270491;7446.925530744623;7453.440991925736;7454.4781865268915;7963;8061.863606144494;8068.757937261891;8070.838583228185;8102.7569172924905;6154.706672592992;7101.11236095488;7138.964305178441;7273.636318184175;7440.813979650552;7242.167150777728;7244.818879528054;7280.781576552712;7369.77890663281;7404.148522919933;6885.449164066762;6891.793246202622;6942.687579059056;6949;6952.374286314256;4052.5339585947995;4968.627352948615;4976.576990955809;4984.75076246179;5156.716380599103;4963.354763880672;4999.850004499871;5003;5061.6962982221185;5141.126008578476 11:51:41 INFO - PROCESS | 1960 | |4;prototype.html;291.1658099544805;311.2219451371571;316.1763605806873;318.98086114833103;320.8395802098951;4981.5553489488;5475.726213689314;5487.6981766002855;5538.307711536058;5557.472040156189;5019.422766381867;5373.382061062979;5380.24676545284;5411.215373770801;5490.80782172624;3838.942415863757;3848.1326267101663;3994.380870965005;4093.631573158415;4182.16356728654;3450.3099380124077;4020.4572382728397;4076.5719599442077;4105.876823695294;4150.564190759972;4044.716869819114;4086.6117718816813;4092.2224777292367;4094.303968325392;4108.219438306712;3413.8975830725117;3567.0725611340977;3651.5070465487083;3837.6546110850018;4086.366613174948;3523.859045638197;3528.012156596157;3532.1699400640828;3554.5023696682483;3571.6606922342335;5155.432902380735;5163.3545806774155;5191.767539957817;5195.272661827347;5217.921731174036;4230.957690423118;4498.8875278117985;4533;4696.107739529512;4809.519048095198;5325.840224793263;5604.523615492688;5627.296587926509;5632.549396048306;5641.351244606882;3934.272159650474;3956.525216973953;3974.682025437958;3976.965988842922;3989.8005099744896;5351.678899266057;5504.422035686235;5521.585881058937;5630.296212973379;5989.401059894019;3976.8608098716404;3996.980015099906;4001.6998725095737;4005.679545636342;4011.277969965376;3464.9307013860084;3469.462233353834;3478.530398396235;3637.3452778500123;4082.408050832613;4347.891302717458;4403.383526306319;4486.820527178941;4692.577668009895;4702.31816386622;5642.391918303264;5654.208410822488;5681.517071049007;5699.914501282484;5770.422957704196;1238.0590751029283;1355.457816873239;1375.2642336350004;1382.8893688504895;1395.1489591349268;715.9248278930742;797.4059325802199;838.995805020971;848.7326492154951;853.9573021348908;4703.412073490814;4968.950310496921;5000.474760246125;5138.717370544581;5233.450487698813;5092.567131793765;5202.788814753714;5454.699991500426;5619.578531610146;5901.262342207224;4376.846810361622;4750.864429476598;4914.975425122851;5189.636725429259;5232.084385232645;3017.9396412071637;3042.6348838139124;3051.649060358043;3069.8618562164756;3100.5194194899377;4950.455449900508;5653.773849046074;6093.29927058394;6123;6186.505079593621;5166.638335316492;5347.572194224453;5365.731713414314;5599.204823008167;5955.493783028404;5204.895902081861;5380.354357477127;5690.439302313893;5904.9332733542915;6145.8489528665505;4995.275685025578;5026.974865125797;5674.943250567441;5715.685392359864;5880.354180408174;5191.662541934762;5354.411014788292;5372.731363431891;5750.568707347049;5800.50695690871;5440.286309812396;5493.560515158858;5777.364489906191;6028.75884964614;6101.267847858286;3674.430463278196;3815.599362066943;3835.6547910688173;3870.903227419337;4002.9399559006056;4931.334269873521;5346.545543628679;5480.91778623313;5776;5834.978878696126;5627.7467513961155;5646.491815736604;5676;5683.3748287687495;5698.974184646803;5369.543588794996;5376.75766128022;5406.08071641325;5642.435756424489;5649.887002260014;5210.571014637528;5274.446183150714;5560.499555040065;5568.331800184003;5570.58220633446;5334.413214546475;5494.395616482264;5550.417206193453;5669.6314739541795;5736.483716465538;4927.408710954708;5147.536721695065;5420.810271640474;5534.612577119563;5547.8890422192135 11:51:41 INFO - PROCESS | 1960 | |5;yui.html;740.3484933258733;768.1300554484567;782.1787123520302;828.3207769628902;843.5782108945527;15151.545453636394;15779.368825246991;15842.524724258263;15882.047077175377;15937.76093358599;14364.712705745878;14413.351399187035;14498.710025799477;14502.202378869184;14522.636934076654;713.914330280367;741.6699568691935;884.0408157149501;887.6183241206279;911.9452832830018;906.2251774732607;915.7847905742178;915.844306467902;916.9816603667923;919.3197034194682;956.5121787888193;971.9319647624669;976.2336565795852;976.3702411944291;977.8630991661173;746.6005686957506;774.6514068669121;777.3431450424382;968.7432830299941;972.9708108756748;578.8639669677623;686.2279935073043;707.678006507038;708.2987842036398;710.5843081797154;12078.879211207863;14744.483943061941;14796.260186990716;14802.40790368384;14846.554603361916;13740.450381984809;13868.035759592778;13960.581182564438;13993.180886484794;14224.786628200585;11935.94032029843;12002.879971200264;12007;12176.452059657337;12373.505059797597;4363.051438448178;4415.094905544388;4481.686281960264;4485.183500682229;4493.393391892086;101967.47048794274;102205.48897255541;102457.9754202456;104080.47959760229;108532.91467085386;11025.558977640965;11079.667609971553;11100.833487497694;11120.777584448428;11281.323120612626;21750.89124554367;21764.34706958762;21774.91125443753;21805.127794888027;21857.890710546344;8073.233042860918;8143.226393492609;8146.307563857138;8179.591020448954;8251.711190108317;4349.652027837766;4414.713043652152;4419.11617676466;4422.402975598317;4587.747673877969;3400.5919289684903;3446.276281980812;3517.9120521987156;3524.9295014099575;3528.7882727035944;614.8401415631968;618.1520037764877;622.1134882792003;625.7590827531433;659.36700767263;527.9894402112013;589.3369958796354;599.3377318063564;606.2028432611103;684.5687217053325;8843;9284.489353085644;9288.256939444827;9289.628414863464;9364.859527107099;517.3533083645443;532.4489153725875;587.4888846703395;601.8865099565855;658.8122385120217;3687.686546643565;4403.625691816167;4403.8458653946955;4419.955800442019;4540.364348991144;112645;134574.3271283657;142388;146918;152599;122827;123793;124757;126204;126422;105563;111281;111583.44208278907;113545.43227283811;114494.42752786349;7183.820404489929;8502.659893604434;8505.574721264036;8638.093000235136;8744.78138046554;48596;62353.37646623476;63281.68359158359;63576.00663927057;64376;83974;87451.12548874429;93671;94645;95803.52098239464;9838.65564705232;11303.943480282545;11330.660080197607;11391.259568128045;11886.465109069939;12074.879251207727;12118.454669540068;12201.20692155007;12259.448324825582;12549.560765373168;11520.827187592025;11683.591074312359;11806.704832379259;11826.053915686896;12154.453049612965;11889;11901.10741694359;11953.402329883646;12200.511979521074;12262.877371226174;11412.258203216765;11606.361650109326;11678.88321116778;11712.70718232051;11929.582464613697;9681.37071090377;9939.950300248453;9978.25163112784;10290.639827605997;10319.535620896928;9373.20327772147;9756.21950243993;9845.360051596623;9891.540992732915;10040.347377420445 11:51:41 INFO - PROCESS | 1960 | __end_tp_report 11:51:41 INFO - PROCESS | 1960 | __start_cc_report 11:51:41 INFO - PROCESS | 1960 | _x_x_mozilla_cycle_collect,1202 11:51:41 INFO - PROCESS | 1960 | __end_cc_report 11:51:41 INFO - PROCESS | 1960 | __startTimestamp1464893501956__endTimestamp 11:51:41 INFO - PROCESS | 1960 | 11:51:41 INFO - PROCESS | 1960 | ------- Summary: start ------- 11:51:41 INFO - PROCESS | 1960 | Number of tests: 6 11:51:41 INFO - PROCESS | 1960 | 11:51:41 INFO - PROCESS | 1960 | [#0] dojo.html Cycles:165 Average:5713.12 Median:5908.14 stddev:634.76 (10.7%) stddev-sans-first:611.23 11:51:41 INFO - PROCESS | 1960 | Values: 3443.4 3454.5 3465.3 3484.0 3533.0 5607.4 5615.9 5703.9 5738.8 5748.3 5664.7 5691.8 5755.0 5796.2 5811.5 5085.1 5112.2 5167.6 5196.3 5201.7 5199.5 5203.9 5221.9 5238.0 5266.3 5119.2 5138.1 5167.1 5195.4 5206.6 5176.5 5184.4 5184.6 5188.1 5191.9 5123.9 5129.2 5146.5 5153.0 5163.8 5563.4 5570.2 5597.5 5657.6 5677.5 6125.6 6172.0 6184.7 6201.3 6210.8 6121.1 6176.0 6229.2 6279.7 6294.9 5743.8 5763.1 5781.2 5827.9 5832.8 6310.0 6315.8 6333.1 6336.7 6349.6 5908.1 6025.5 6032.2 6093.6 6122.9 6172.4 6199.2 6210.7 6217.1 6222.6 5525.1 5888.3 6201.2 6213.3 6214.4 6171.6 6175.6 6259.3 6263.9 6269.8 4944.5 4945.7 4948.5 4950.0 4975.1 4339.1 4368.8 4405.0 4445.7 4484.2 4565.5 5684.1 5684.8 5708.7 5801.9 6258.8 6298.6 6301.3 6322.8 6346.9 5448.5 5584.6 5586.1 5647.5 5653.5 5382.8 5387.6 5403.1 5406.6 5410.4 6329.8 6332.5 6348.6 6373.6 6375.0 6129.4 6191.5 6242.8 6243.9 6345.6 6251.2 6283.1 6283.7 6288.4 6291.6 5948.3 6175.4 6188.4 6212.2 6219.4 6127.6 6167.0 6193.2 6253.0 6293.7 6062.0 6096.9 6102.0 6118.7 6125.0 4889.2 5942.3 6088.7 6095.4 6141.4 5783.5 5916.6 5944.3 5950.3 5960.2 6098.6 6102.3 6104.7 6110.4 6121.9 5738.3 5785.7 5834.7 5923.3 5932.5 11:51:41 INFO - PROCESS | 1960 | 11:51:41 INFO - PROCESS | 1960 | [#1] ext.html Cycles:180 Average:58510.91 Median:35729.41 stddev:127763.83 (357.6%) stddev-sans-first:128047.95 11:51:41 INFO - PROCESS | 1960 | Values: 482.2 502.8 504.2 510.9 563.6 31017.2 41504.8 42798.1 42860.1 44764.3 44140.9 44494.8 44662.6 44683.8 44700.3 8463.0 9484.5 9737.0 9743.5 9744.9 8611.7 11484.4 11843.1 11844.9 11855.5 11339.8 11617.7 11673.1 11698.1 11727.2 8584.1 8834.7 8848.8 11585.7 11710.6 8630.6 9118.8 11527.6 11531.0 11793.9 41281.0 41673.0 41755.4 41974.8 42007.9 3487.3 3508.0 3519.2 3618.8 3851.6 31735.8 32420.5 32444.5 32674.7 33007.0 3236.4 3260.7 3272.1 3298.9 3396.2 512700.0 514594.0 515604.0 516288.4 516317.0 32803.5 33701.3 61408.4 61834.0 61944.7 36055.8 36244.5 36407.8 36418.0 36459.3 14973.2 15343.9 15349.9 15350.5 15356.4 11655.6 11901.4 12597.2 12778.0 12910.1 7961.2 7967.0 7998.6 8037.6 8066.6 3208.6 3220.4 3222.3 3243.3 3258.1 853.8 939.3 1024.7 1072.1 1098.5 24978.6 29238.3 30547.0 30671.2 31510.7 1087.6 1092.3 1177.7 1180.8 1197.4 12988.7 16025.0 16045.1 16327.0 16461.4 181382.0 181860.0 181979.0 182433.0 183353.0 59297.0 59901.8 59937.1 60032.0 60054.4 1259.7 1310.3 1310.7 1384.3 1425.9 22312.3 25709.9 30120.4 30540.0 32224.2 56215.7 57172.7 57188.1 57289.7 57510.4 592703.0 619614.0 620872.0 621325.0 623441.0 23801.9 24034.6 26018.9 26935.0 27244.9 47187.3 47942.3 48194.5 48240.6 48249.5 37710.6 40039.9 41044.0 42498.8 43609.8 39388.4 39947.0 40525.8 40987.6 41305.8 38550.6 38806.2 38998.2 39005.4 39311.8 33225.7 34910.7 35413.8 36045.0 36538.8 25634.4 25843.6 25910.9 26162.7 26192.6 11:51:41 INFO - PROCESS | 1960 | 11:51:41 INFO - PROCESS | 1960 | [#2] jquery.html Cycles:180 Average:34334.05 Median:13485.73 stddev:97154.45 (720.4%) stddev-sans-first:97393.54 11:51:41 INFO - PROCESS | 1960 | Values: 379.4 379.7 385.9 394.6 403.9 19245.5 20754.3 21007.3 21315.3 21323.6 20523.9 20747.8 20865.9 21043.9 21218.9 5206.7 5845.4 6010.8 6054.9 6064.3 5938.6 5971.6 5985.7 6000.6 6041.9 5700.3 5886.0 5887.0 5887.5 5901.8 5830.0 5883.8 5887.3 5939.3 5955.0 4967.3 5104.3 5145.4 5158.2 5158.4 24706.0 25030.7 25390.9 25482.4 26005.6 2234.8 2235.5 2265.5 2606.2 2756.0 14471.6 15285.9 15463.9 15545.0 15996.8 1826.2 1843.4 1852.3 1876.0 1905.8 114377.0 114998.3 115272.4 115897.0 116800.0 9682.6 10488.6 10616.8 10900.1 11016.2 12776.9 13491.7 13648.3 13655.3 13673.9 7032.9 7143.7 7272.5 8587.9 8708.0 8626.9 8635.4 8690.0 8737.6 8814.6 6516.9 6645.8 6662.8 6680.7 6738.0 1368.4 1385.5 1798.9 1840.2 1842.9 830.7 941.6 959.6 967.0 1085.5 16966.7 17943.4 20813.5 20933.1 21815.3 965.9 967.9 1031.7 1035.2 1073.9 9245.7 11724.4 11743.9 11753.1 11832.7 570109.0 578579.0 582623.0 588854.0 591534.0 123556.0 124023.1 125163.0 126862.7 127721.7 900.4 1014.5 1032.2 1053.6 1055.1 18945.4 19253.0 19255.2 19351.9 19413.9 43379.1 49943.0 50747.0 51156.7 51692.0 73962.0 74322.0 74408.6 75601.9 76396.0 7090.5 7879.6 7898.6 7995.8 8201.0 12667.0 12697.0 13002.3 13016.4 13237.8 11102.6 11108.6 11315.7 11398.8 11449.9 10901.5 11062.9 11152.4 11239.3 11427.4 5878.5 8004.8 9334.4 11494.9 11668.0 7402.4 7487.7 7524.2 7531.8 7659.5 10020.7 10798.4 10956.5 11351.5 11423.0 11:51:41 INFO - PROCESS | 1960 | 11:51:41 INFO - PROCESS | 1960 | [#3] mootools.html Cycles:180 Average:5514.05 Median:5111.58 stddev:3066.20 (60.0%) stddev-sans-first:3058.96 11:51:41 INFO - PROCESS | 1960 | Values: 1367.5 1530.3 1558.8 1761.7 1906.1 7646.7 8350.9 8580.1 8589.6 8694.8 6626.5 7250.6 8519.5 8578.4 8591.4 2289.7 2318.0 2324.8 2325.7 2326.2 2267.9 2306.5 2311.6 2314.8 2317.3 2234.8 2241.5 2249.0 2306.3 2309.4 2246.0 2247.7 2253.0 2254.6 2256.4 2266.1 2271.7 2274.5 2274.9 2279.9 8289.8 8470.5 8538.9 8545.0 8556.4 5142.4 5284.8 5286.0 5293.2 5299.5 3450.0 3497.9 3554.3 3570.3 3571.7 1043.2 1044.1 1050.2 1062.5 1065.7 11203.2 11230.6 11239.7 11254.0 11290.5 4326.6 4367.9 4484.5 4485.9 4487.9 8924.6 8933.0 8953.4 8960.0 8963.9 5099.2 5107.6 5111.2 5111.9 5141.8 4285.8 4437.8 4612.9 4642.0 4759.9 3655.6 3681.8 3723.3 3753.4 3825.4 1125.0 1127.0 1129.2 1135.6 1137.6 646.7 724.6 817.7 861.8 977.3 5578.8 5682.6 5865.3 5906.5 5995.2 927.2 956.5 957.1 980.0 987.1 3941.3 4012.7 4020.4 4039.7 4058.2 9897.0 10503.5 10516.9 10524.5 10538.4 10090.4 10324.2 10476.6 10486.3 10501.8 7811.6 7966.2 8020.8 8034.6 8036.3 5050.4 5134.1 5598.8 5673.5 5764.5 9326.0 9522.1 9744.5 9750.3 9751.8 9663.6 9755.0 9788.9 9796.4 9808.0 7267.9 7317.3 7446.9 7453.4 7454.5 7963.0 8061.9 8068.8 8070.8 8102.8 6154.7 7101.1 7139.0 7273.6 7440.8 7242.2 7244.8 7280.8 7369.8 7404.1 6885.4 6891.8 6942.7 6949.0 6952.4 4052.5 4968.6 4976.6 4984.8 5156.7 4963.4 4999.9 5003.0 5061.7 5141.1 11:51:41 INFO - PROCESS | 1960 | 11:51:41 INFO - PROCESS | 1960 | [#4] prototype.html Cycles:180 Average:4557.89 Median:5191.72 stddev:1389.63 (26.8%) stddev-sans-first:1356.12 11:51:41 INFO - PROCESS | 1960 | Values: 291.2 311.2 316.2 319.0 320.8 4981.6 5475.7 5487.7 5538.3 5557.5 5019.4 5373.4 5380.2 5411.2 5490.8 3838.9 3848.1 3994.4 4093.6 4182.2 3450.3 4020.5 4076.6 4105.9 4150.6 4044.7 4086.6 4092.2 4094.3 4108.2 3413.9 3567.1 3651.5 3837.7 4086.4 3523.9 3528.0 3532.2 3554.5 3571.7 5155.4 5163.4 5191.8 5195.3 5217.9 4231.0 4498.9 4533.0 4696.1 4809.5 5325.8 5604.5 5627.3 5632.5 5641.4 3934.3 3956.5 3974.7 3977.0 3989.8 5351.7 5504.4 5521.6 5630.3 5989.4 3976.9 3997.0 4001.7 4005.7 4011.3 3464.9 3469.5 3478.5 3637.3 4082.4 4347.9 4403.4 4486.8 4692.6 4702.3 5642.4 5654.2 5681.5 5699.9 5770.4 1238.1 1355.5 1375.3 1382.9 1395.1 715.9 797.4 839.0 848.7 854.0 4703.4 4969.0 5000.5 5138.7 5233.5 5092.6 5202.8 5454.7 5619.6 5901.3 4376.8 4750.9 4915.0 5189.6 5232.1 3017.9 3042.6 3051.6 3069.9 3100.5 4950.5 5653.8 6093.3 6123.0 6186.5 5166.6 5347.6 5365.7 5599.2 5955.5 5204.9 5380.4 5690.4 5904.9 6145.8 4995.3 5027.0 5674.9 5715.7 5880.4 5191.7 5354.4 5372.7 5750.6 5800.5 5440.3 5493.6 5777.4 6028.8 6101.3 3674.4 3815.6 3835.7 3870.9 4002.9 4931.3 5346.5 5480.9 5776.0 5835.0 5627.7 5646.5 5676.0 5683.4 5699.0 5369.5 5376.8 5406.1 5642.4 5649.9 5210.6 5274.4 5560.5 5568.3 5570.6 5334.4 5494.4 5550.4 5669.6 5736.5 4927.4 5147.5 5420.8 5534.6 5547.9 11:51:41 INFO - PROCESS | 1960 | 11:51:41 INFO - PROCESS | 1960 | [#5] yui.html Cycles:180 Average:23906.34 Median:4403.74 stddev:38176.55 (866.9%) stddev-sans-first:38244.02 11:51:41 INFO - PROCESS | 1960 | Values: 740.3 768.1 782.2 828.3 843.6 15151.5 15779.4 15842.5 15882.0 15937.8 14364.7 14413.4 14498.7 14502.2 14522.6 713.9 741.7 884.0 887.6 911.9 906.2 915.8 915.8 917.0 919.3 956.5 971.9 976.2 976.4 977.9 746.6 774.7 777.3 968.7 973.0 578.9 686.2 707.7 708.3 710.6 12078.9 14744.5 14796.3 14802.4 14846.6 13740.5 13868.0 13960.6 13993.2 14224.8 11935.9 12002.9 12007.0 12176.5 12373.5 4363.1 4415.1 4481.7 4485.2 4493.4 101967.5 102205.5 102458.0 104080.5 108532.9 11025.6 11079.7 11100.8 11120.8 11281.3 21750.9 21764.3 21774.9 21805.1 21857.9 8073.2 8143.2 8146.3 8179.6 8251.7 4349.7 4414.7 4419.1 4422.4 4587.7 3400.6 3446.3 3517.9 3524.9 3528.8 614.8 618.2 622.1 625.8 659.4 528.0 589.3 599.3 606.2 684.6 8843.0 9284.5 9288.3 9289.6 9364.9 517.4 532.4 587.5 601.9 658.8 3687.7 4403.6 4403.8 4420.0 4540.4 112645.0 134574.3 142388.0 146918.0 152599.0 122827.0 123793.0 124757.0 126204.0 126422.0 105563.0 111281.0 111583.4 113545.4 114494.4 7183.8 8502.7 8505.6 8638.1 8744.8 48596.0 62353.4 63281.7 63576.0 64376.0 83974.0 87451.1 93671.0 94645.0 95803.5 9838.7 11303.9 11330.7 11391.3 11886.5 12074.9 12118.5 12201.2 12259.4 12549.6 11520.8 11683.6 11806.7 11826.1 12154.5 11889.0 11901.1 11953.4 12200.5 12262.9 11412.3 11606.4 11678.9 11712.7 11929.6 9681.4 9940.0 9978.3 10290.6 10319.5 9373.2 9756.2 9845.4 9891.5 10040.3 11:51:41 INFO - PROCESS | 1960 | -------- Summary: end -------- 11:51:41 INFO - PROCESS | 1960 | 11:51:42 INFO - TEST-INFO | 1960: exit 0 11:51:42 INFO - TEST-OK | dromaeo_css | took 1115796ms 11:51:42 INFO - TEST-START | kraken 11:51:42 INFO - operating with platform_type : mac_ 11:51:42 INFO - Initialising browser for kraken test... 11:51:42 INFO - TEST-INFO | started process 1964 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpV84ml9/profile http://localhost:53221/getInfo.html) 11:51:44 INFO - PROCESS | 1964 | __metrics Screen width/height:1600/1200 11:51:44 INFO - PROCESS | 1964 | colorDepth:24 11:51:44 INFO - PROCESS | 1964 | Browser inner width/height: 1024/689 11:51:44 INFO - PROCESS | 1964 | __metrics 11:51:44 INFO - PROCESS | 1964 | 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" 11:51:44 ERROR - PROCESS | 1964 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:51:45 INFO - TEST-INFO | 1964: exit 0 11:51:45 INFO - Browser initialized. 11:51:45 INFO - Running cycle 1/1 for kraken test... 11:51:45 INFO - TEST-INFO | started process 1965 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpV84ml9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:51:57 INFO - PROCESS | 1965 | RSS: Main: 242286592 11:51:57 INFO - PROCESS | 1965 | 11:52:00 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:53221/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53221/tests/kraken/driver.html?testName=audio-beat-detection) 11:52:01 INFO - PROCESS | 1965 | RSS: Main: 292376576 11:52:01 INFO - PROCESS | 1965 | 11:52:04 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:53221/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53221/tests/kraken/driver.html?testName=audio-dft) 11:52:04 INFO - PROCESS | 1965 | RSS: Main: 302096384 11:52:04 INFO - PROCESS | 1965 | 11:52:08 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:53221/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53221/tests/kraken/driver.html?testName=audio-fft) 11:52:08 INFO - PROCESS | 1965 | RSS: Main: 390324224 11:52:08 INFO - PROCESS | 1965 | 11:52:10 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:53221/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53221/tests/kraken/driver.html?testName=audio-oscillator) 11:52:11 INFO - PROCESS | 1965 | RSS: Main: 291033088 11:52:11 INFO - PROCESS | 1965 | 11:52:13 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:53221/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53221/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:52:14 INFO - PROCESS | 1965 | RSS: Main: 294981632 11:52:14 INFO - PROCESS | 1965 | 11:52:17 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:53221/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53221/tests/kraken/driver.html?testName=imaging-darkroom) 11:52:17 INFO - PROCESS | 1965 | RSS: Main: 330215424 11:52:17 INFO - PROCESS | 1965 | 11:52:20 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:53221/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53221/tests/kraken/driver.html?testName=imaging-desaturate) 11:52:21 INFO - PROCESS | 1965 | RSS: Main: 334647296 11:52:21 INFO - PROCESS | 1965 | 11:52:24 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:53221/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53221/tests/kraken/driver.html?testName=json-parse-financial) 11:52:24 INFO - PROCESS | 1965 | RSS: Main: 334139392 11:52:24 INFO - PROCESS | 1965 | 11:52:26 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:53221/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53221/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:52:27 INFO - PROCESS | 1965 | RSS: Main: 310185984 11:52:27 INFO - PROCESS | 1965 | 11:52:28 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:53221/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53221/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:52:29 INFO - PROCESS | 1965 | RSS: Main: 316383232 11:52:29 INFO - PROCESS | 1965 | 11:52:31 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:53221/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53221/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:52:32 INFO - PROCESS | 1965 | RSS: Main: 321183744 11:52:32 INFO - PROCESS | 1965 | 11:52:35 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:53221/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:52:35 INFO - PROCESS | 1965 | RSS: Main: 324505600 11:52:35 INFO - PROCESS | 1965 | 11:52:39 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:53221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:52:39 INFO - PROCESS | 1965 | RSS: Main: 323817472 11:52:39 INFO - PROCESS | 1965 | 11:52:41 INFO - PROCESS | 1965 | Cycle 1(1): loaded http://localhost:53221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53221/tests/kraken/driver.html?testName=ai-astar) 11:52:41 INFO - PROCESS | 1965 | RSS: Main: 367849472 11:52:41 INFO - PROCESS | 1965 | 11:52:41 INFO - PROCESS | 1965 | __start_tp_report 11:52:41 INFO - PROCESS | 1965 | _x_x_mozilla_page_load 11:52:41 INFO - PROCESS | 1965 | _x_x_mozilla_page_load_details 11:52:41 INFO - PROCESS | 1965 | |i|pagename|runs| 11:52:41 INFO - PROCESS | 1965 | |0;ai-astar;114;106;90;155;164;166;134;112;113;111 11:52:41 INFO - PROCESS | 1965 | |1;audio-beat-detection;162;144;110;144;142;146;164;135;173;141 11:52:41 INFO - PROCESS | 1965 | |2;audio-dft;185;185;185;183;182;187;183;182;186;184 11:52:41 INFO - PROCESS | 1965 | |3;audio-fft;80;74;58;87;59;57;65;65;102;78 11:52:41 INFO - PROCESS | 1965 | |4;audio-oscillator;84;90;93;72;72;69;97;71;68;105 11:52:41 INFO - PROCESS | 1965 | |5;imaging-gaussian-blur;89;86;90;86;89;86;89;89;134;107 11:52:41 INFO - PROCESS | 1965 | |6;imaging-darkroom;94;94;95;95;95;95;95;114;149;149 11:52:41 INFO - PROCESS | 1965 | |7;imaging-desaturate;86;85;85;87;95;106;86;84;88;86 11:52:41 INFO - PROCESS | 1965 | |8;json-parse-financial;53;55;56;56;57;55;55;55;55;56 11:52:41 INFO - PROCESS | 1965 | |9;json-stringify-tinderbox;67;66;83;74;72;69;72;75;71;72 11:52:41 INFO - PROCESS | 1965 | |10;stanford-crypto-aes;88;87;87;86;88;87;88;89;147;86 11:52:41 INFO - PROCESS | 1965 | |11;stanford-crypto-ccm;139;132;143;140;143;139;137;138;149;142 11:52:41 INFO - PROCESS | 1965 | |12;stanford-crypto-pbkdf2;167;228;224;163;143;190;227;152;232;230 11:52:41 INFO - PROCESS | 1965 | |13;stanford-crypto-sha256-iterative;65;65;68;67;66;66;67;67;66;66 11:52:41 INFO - PROCESS | 1965 | __end_tp_report 11:52:41 INFO - PROCESS | 1965 | __start_cc_report 11:52:41 INFO - PROCESS | 1965 | _x_x_mozilla_cycle_collect,2014 11:52:41 INFO - PROCESS | 1965 | __end_cc_report 11:52:41 INFO - PROCESS | 1965 | __startTimestamp1464893561723__endTimestamp 11:52:41 INFO - PROCESS | 1965 | 11:52:41 INFO - PROCESS | 1965 | ------- Summary: start ------- 11:52:41 INFO - PROCESS | 1965 | Number of tests: 14 11:52:41 INFO - PROCESS | 1965 | 11:52:41 INFO - PROCESS | 1965 | [#0] ai-astar Cycles:10 Average:126.50 Median:144.50 stddev:26.62 (18.4%) stddev-sans-first:27.85 11:52:41 INFO - PROCESS | 1965 | Values: 114.0 106.0 90.0 155.0 164.0 166.0 134.0 112.0 113.0 111.0 11:52:41 INFO - PROCESS | 1965 | 11:52:41 INFO - PROCESS | 1965 | [#1] audio-beat-detection Cycles:10 Average:146.10 Median:145.00 stddev:17.56 (12.1%) stddev-sans-first:17.66 11:52:41 INFO - PROCESS | 1965 | Values: 162.0 144.0 110.0 144.0 142.0 146.0 164.0 135.0 173.0 141.0 11:52:41 INFO - PROCESS | 1965 | 11:52:41 INFO - PROCESS | 1965 | [#2] audio-dft Cycles:10 Average:184.20 Median:185.00 stddev:1.69 (0.9%) stddev-sans-first:1.76 11:52:41 INFO - PROCESS | 1965 | Values: 185.0 185.0 185.0 183.0 182.0 187.0 183.0 182.0 186.0 184.0 11:52:41 INFO - PROCESS | 1965 | 11:52:41 INFO - PROCESS | 1965 | [#3] audio-fft Cycles:10 Average:72.50 Median:69.50 stddev:14.58 (21.0%) stddev-sans-first:15.22 11:52:41 INFO - PROCESS | 1965 | Values: 80.0 74.0 58.0 87.0 59.0 57.0 65.0 65.0 102.0 78.0 11:52:41 INFO - PROCESS | 1965 | 11:52:41 INFO - PROCESS | 1965 | [#4] audio-oscillator Cycles:10 Average:82.10 Median:78.00 stddev:13.45 (17.2%) stddev-sans-first:14.25 11:52:41 INFO - PROCESS | 1965 | Values: 84.0 90.0 93.0 72.0 72.0 69.0 97.0 71.0 68.0 105.0 11:52:41 INFO - PROCESS | 1965 | 11:52:41 INFO - PROCESS | 1965 | [#5] imaging-gaussian-blur Cycles:10 Average:94.50 Median:89.00 stddev:15.18 (17.1%) stddev-sans-first:15.97 11:52:41 INFO - PROCESS | 1965 | Values: 89.0 86.0 90.0 86.0 89.0 86.0 89.0 89.0 134.0 107.0 11:52:41 INFO - PROCESS | 1965 | 11:52:41 INFO - PROCESS | 1965 | [#6] imaging-darkroom Cycles:10 Average:107.50 Median:95.00 stddev:22.69 (23.9%) stddev-sans-first:23.53 11:52:41 INFO - PROCESS | 1965 | Values: 94.0 94.0 95.0 95.0 95.0 95.0 95.0 114.0 149.0 149.0 11:52:41 INFO - PROCESS | 1965 | 11:52:41 INFO - PROCESS | 1965 | [#7] imaging-desaturate Cycles:10 Average:88.80 Median:86.00 stddev:6.78 (7.9%) stddev-sans-first:7.11 11:52:41 INFO - PROCESS | 1965 | Values: 86.0 85.0 85.0 87.0 95.0 106.0 86.0 84.0 88.0 86.0 11:52:41 INFO - PROCESS | 1965 | 11:52:41 INFO - PROCESS | 1965 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.50 stddev:1.06 (1.9%) stddev-sans-first:0.73 11:52:41 INFO - PROCESS | 1965 | Values: 53.0 55.0 56.0 56.0 57.0 55.0 55.0 55.0 55.0 56.0 11:52:41 INFO - PROCESS | 1965 | 11:52:41 INFO - PROCESS | 1965 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:72.00 stddev:4.77 (6.6%) stddev-sans-first:4.69 11:52:41 INFO - PROCESS | 1965 | Values: 67.0 66.0 83.0 74.0 72.0 69.0 72.0 75.0 71.0 72.0 11:52:41 INFO - PROCESS | 1965 | 11:52:41 INFO - PROCESS | 1965 | [#10] stanford-crypto-aes Cycles:10 Average:93.30 Median:87.50 stddev:18.89 (21.6%) stddev-sans-first:19.94 11:52:41 INFO - PROCESS | 1965 | Values: 88.0 87.0 87.0 86.0 88.0 87.0 88.0 89.0 147.0 86.0 11:52:41 INFO - PROCESS | 1965 | 11:52:41 INFO - PROCESS | 1965 | [#11] stanford-crypto-ccm Cycles:10 Average:140.20 Median:141.00 stddev:4.49 (3.2%) stddev-sans-first:4.74 11:52:41 INFO - PROCESS | 1965 | Values: 139.0 132.0 143.0 140.0 143.0 139.0 137.0 138.0 149.0 142.0 11:52:41 INFO - PROCESS | 1965 | 11:52:41 INFO - PROCESS | 1965 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.60 Median:225.50 stddev:36.41 (16.1%) stddev-sans-first:37.12 11:52:41 INFO - PROCESS | 1965 | Values: 167.0 228.0 224.0 163.0 143.0 190.0 227.0 152.0 232.0 230.0 11:52:41 INFO - PROCESS | 1965 | 11:52:41 INFO - PROCESS | 1965 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:0.95 (1.4%) stddev-sans-first:0.88 11:52:41 INFO - PROCESS | 1965 | Values: 65.0 65.0 68.0 67.0 66.0 66.0 67.0 67.0 66.0 66.0 11:52:41 INFO - PROCESS | 1965 | -------- Summary: end -------- 11:52:41 INFO - PROCESS | 1965 | 11:52:42 INFO - TEST-INFO | 1965: exit 0 11:52:42 INFO - TEST-OK | kraken | took 59934ms 11:52:42 INFO - SUITE-END | took 1175s 11:52:42 INFO - Completed test suite (00:19:36) 11:52:42 INFO - javascript benchmark 11:52:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3443.362977849098, 3454.481827725841, 3465.3069386122775, 3484.0, 3532.964670353296, 5607.4112218217115, 5615.887682246353, 5703.914441283374, 5738.7991420300295, 5748.2527271454655, 5664.688442135681, 5691.800786972457, 5754.971225143879, 5796.159556864262, 5811.535077193815, 5085.1355269604255, 5112.156494178456, 5167.612429067816, 5196.324477817879, 5201.661891977028, 5199.532042116209, 5203.869903252411, 5221.869453263661, 5238.030964271603, 5266.262723218733, 5119.2321151827155, 5138.126518491866, 5167.121589329823, 5195.376554813428, 5206.635535512534, 5176.534111929906, 5184.377874655065, 5184.585233181337, 5188.066148093341, 5191.896162076738, 5123.871903202413, 5129.204973229155, 5146.536811686929, 5152.974235128838, 5163.793448262065, 5563.443655634444, 5570.2480165177585, 5597.496225339698, 5657.57568182388, 5677.517411020068, 6125.6170790510105, 6172.0, 6184.6907654617535, 6201.348858369851, 6210.7826226082325, 6121.143039974407, 6176.011838105882, 6229.221347331583, 6279.654618995954, 6294.874102517924, 5743.827685169452, 5763.135529670499, 5781.219535362672, 5827.854303642443, 5832.766689332379, 6309.968450157811, 6315.842103947346, 6333.113364129025, 6336.714847831859, 6349.555531112777, 5908.14331921869, 6025.487833534111, 6032.155498230251, 6093.634381937098, 6122.908156377658, 6172.351903050115, 6199.225096862892, 6210.689465526705, 6217.098520714471, 6222.564420490522, 5525.11598144303, 5888.322842873039, 6201.224846894138, 6213.316535181127, 6214.40963108504, 6171.56799024073, 6175.567710260329, 6259.311475737665, 6263.874722505616, 6269.843253918597, 4944.53026962438, 4945.727984960861, 4948.455669876311, 4950.0, 4975.0796102721115, 4339.1104823511105, 4368.847090351823, 4404.955950440519, 4445.688801783909, 4484.215262329145, 4565.474970378383, 5684.118961560965, 5684.77260909559, 5708.65748055118, 5801.8549536261935, 6258.812235632939, 6298.622831633916, 6301.306856245809, 6322.778702745474, 6346.936530634727, 5448.509634132868, 5584.609077364546, 5586.050371436865, 5647.548196144381, 5653.491185793298, 5382.784688612412, 5387.649802762886, 5403.13549832025, 5406.648567843077, 5410.377806552218, 6329.810105696836, 6332.4934005279465, 6348.555601107971, 6373.553851230555, 6375.0, 6129.358790882775, 6191.473724733448, 6242.750289988531, 6243.937560624336, 6345.555811093179, 6251.218597675291, 6283.120363149072, 6283.717232724447, 6288.402601752826, 6291.559590828781, 5948.315943666534, 6175.44421002112, 6188.381161883775, 6212.223472065992, 6219.40915613016, 6127.601705889103, 6167.0, 6193.194884664965, 6252.968735156295, 6293.748250069946, 6061.969690151521, 6096.9390306096375, 6102.0, 6118.694065296629, 6124.969375153096, 4889.193283108248, 5942.286925568959, 6088.730290291695, 6095.420935011167, 6141.447269745744, 5783.479486846204, 5916.585838991229, 5944.256967879015, 5950.285965684146, 5960.195373624505, 6098.573099882966, 6102.298235702951, 6104.725287361991, 6110.358412366815, 6121.877562448637, 5738.311402631711, 5785.710714464344, 5834.679092649945, 5923.289205295392, 5932.466078052996], "value": 5674.093326603706, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.2284345047924, 502.8441183233198, 504.2486694824712, 510.8824970256841, 563.5857644631196, 31017.224569385748, 41504.79247603761, 42798.14403711924, 42860.142797144115, 44764.328535071916, 44140.896477587994, 44494.777526112324, 44662.553374466246, 44683.77658111705, 44700.32949505752, 8463.0, 9484.478353690562, 9736.951315243414, 9743.51282435879, 9744.853827192597, 8611.741647750547, 11484.36835974021, 11843.052555795559, 11844.94077529611, 11855.466504007298, 11339.829902551468, 11617.6514704559, 11673.124515661317, 11698.122640801932, 11727.237729547553, 8584.141585841366, 8834.69078582253, 8848.823023539493, 11585.710357241094, 11710.590129345517, 8630.568471576396, 9118.81762364749, 11527.596534121349, 11531.0, 11793.941030294794, 41280.967975800544, 41673.0, 41755.37366939468, 41974.79012604948, 42007.94980125491, 3487.2851065531504, 3508.0, 3519.2257703305486, 3618.8009659468717, 3851.5763266040713, 31735.84132079325, 32420.513692294633, 32444.513332300034, 32674.6732532674, 33007.0, 3236.433624115794, 3260.739140868725, 3272.083816531351, 3298.934021319584, 3396.235846934445, 512700.0, 514594.0, 515604.0, 516288.41855790856, 516317.0, 32803.50794738081, 33701.325973480394, 61408.385916140265, 61834.0, 61944.69027654833, 36055.81972090175, 36244.4563331545, 36407.817960910026, 36418.0, 36459.27081458356, 14973.176475293963, 15343.923280383528, 15349.9232503839, 15350.539483815503, 15356.385744570092, 11655.59205427806, 11901.404929753478, 12597.181183223061, 12778.041646876522, 12910.096293259381, 7961.16407777175, 7967.0, 7998.5600791957, 8037.557934313669, 8066.5563394012715, 3208.6149662040702, 3220.3559288142465, 3222.3394204188085, 3243.2540515681053, 3258.1365938026443, 853.7737499562621, 939.2767568971854, 1024.666983230453, 1072.0780129088978, 1098.5441041967654, 24978.625320620205, 29238.26904327409, 30547.0, 30671.23321916925, 31510.684893150774, 1087.6410784441273, 1092.2900114925362, 1177.6879127031343, 1180.7992641251008, 1197.4379009500994, 12988.675283117998, 16025.0, 16045.117518536592, 16327.0, 16461.423850165185, 181382.0, 181860.0, 181979.0, 182433.0, 183353.0, 59297.0, 59901.801963960475, 59937.10094348501, 60032.0, 60054.39945600488, 1259.7480503899258, 1310.3382791690135, 1310.652677040604, 1384.3286006286942, 1425.9001869869217, 22312.330630081124, 25709.871450642626, 30120.397592048474, 30540.0, 32224.19439513937, 56215.71892140513, 57172.71413642905, 57188.1421778682, 57289.713551431974, 57510.424895750504, 592703.0, 619614.0, 620872.0, 621325.0, 623441.0, 23801.928913199295, 24034.63948040746, 26018.86990565035, 26935.0, 27244.863775680995, 47187.29219061785, 47942.28086578774, 48194.518054819004, 48240.552783416555, 48249.517504825904, 37710.62289377071, 40039.85051101878, 41044.0, 42498.78750606227, 43609.781951090044, 39388.40917386299, 39947.0, 40525.79737101296, 40987.59012409838, 41305.793471032455, 38550.6144938547, 38806.2238755229, 38998.2200355997, 39005.41491877567, 39311.803440982614, 33225.66774332226, 34910.65089349074, 35413.82293088518, 36045.0, 36538.8173059133, 25634.35914102088, 25843.612345815203, 25910.870445648405, 26162.73837261679, 26192.607110893732], "value": 18573.717506877987, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.4460088271124, 379.70383101181073, 385.90645746823475, 394.64087680211014, 403.8707613563659, 19245.51886202844, 20754.27360042399, 21007.2647457339, 21315.25396611117, 21323.57352852942, 20523.897380513114, 20747.792522074775, 20865.895670521662, 21043.894780526116, 21218.89390553045, 5206.687598744069, 5845.357010728827, 6010.759569617232, 6054.939450605481, 6064.3026052003925, 5938.554608404365, 5971.581989260753, 5985.730642121093, 6000.639961602296, 6041.939580604182, 5700.258966334371, 5886.0, 5887.0, 5887.499562537189, 5901.763929442817, 5830.0, 5883.8234852954265, 5887.264091988502, 5939.287285525743, 5955.047192449188, 4967.279744437036, 5104.259882317043, 5145.408278048035, 5158.200478925773, 5158.3552055993005, 24706.0, 25030.749692503025, 25390.87304563484, 25482.36294092644, 26005.609915851277, 2234.8100411465043, 2235.508188198594, 2265.456290490287, 2606.2181345596437, 2756.0, 14471.638209044753, 15285.929984901062, 15463.912371829772, 15545.027454556232, 15996.840031599651, 1826.205600563759, 1843.4377514857963, 1852.2590963614527, 1875.990620046891, 1905.7903630600622, 114377.0, 114998.27502587468, 115272.42363788211, 115897.0, 116800.0, 9682.612695492102, 10488.632897848538, 10616.787664246825, 10900.073493752961, 11016.228863979604, 12776.872231277754, 13491.662708432172, 13648.317584120754, 13655.317234138254, 13673.863261367458, 7032.859342813115, 7143.714251429885, 7272.49092563526, 8587.914120858712, 8708.042115367305, 8626.929244088722, 8635.438696484709, 8689.956550217208, 8737.60680769367, 8814.603342849587, 6516.934830651633, 6645.843224468339, 6662.800115996528, 6680.699368528428, 6737.9556168793915, 1368.4047439363908, 1385.4873696732272, 1798.9190486428142, 1840.1811194018533, 1842.9170687319104, 830.7258604660568, 941.580996456584, 959.5681943125621, 966.9854952175723, 1085.5332207151, 16966.660666786596, 17943.371981980566, 20813.479663008242, 20933.05801238948, 21815.34553963352, 965.8551217317458, 967.9274054445945, 1031.7162780235494, 1035.2080658296409, 1073.897979691928, 9245.72262832116, 11724.355160466086, 11743.941280293715, 11753.118516111326, 11832.704182395337, 570109.0, 578579.0, 582623.0, 588854.0, 591534.0, 123556.0, 124023.13965290527, 125163.0, 126862.73137268693, 127721.72278277283, 900.3607438718583, 1014.4826138669332, 1032.210359075308, 1053.6030530543076, 1055.1242468750918, 18945.43163705091, 19253.037348132817, 19255.22979080821, 19351.90324048371, 19413.902930485256, 43379.132417352106, 49943.00113997627, 50747.0, 51156.72108197176, 51692.0, 73962.0, 74322.0, 74408.62795685987, 75601.91739314141, 76396.0, 7090.503664743418, 7879.645415956181, 7898.644560994884, 7995.840083198187, 8201.0, 12667.0, 12697.0, 13002.349882506027, 13016.414261358072, 13237.801432978706, 11102.555897764321, 11108.555657773599, 11315.717107072389, 11398.829017564578, 11449.885501144881, 10901.509432075418, 11062.949019843101, 11152.442377881236, 11239.269447485887, 11427.428628568705, 5878.529717622666, 8004.799880003046, 9334.39326443779, 11494.873448411736, 11668.008219301455, 7402.4078073755045, 7487.737929172671, 7524.17234104259, 7531.774046778605, 7659.540427574363, 10020.697309349738, 10798.406087665255, 10956.452177391258, 11351.545938162712, 11423.0], "value": 9613.238348198358, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1367.4940272099311, 1530.3190080414213, 1558.8363221861705, 1761.7005109131446, 1906.142235993803, 7646.732364367248, 8350.916490835098, 8580.056193818675, 8589.570521473925, 8694.782630434242, 6626.469882409408, 7250.601216933067, 8519.53142577158, 8578.39951203416, 8591.398602097855, 2289.713785776778, 2318.038014224095, 2324.8140148788143, 2325.6627788970673, 2326.150954901457, 2267.85258958168, 2306.4695120122383, 2311.6070268054473, 2314.8148148148107, 2317.281642690763, 2234.832387570939, 2241.529278851435, 2249.0216755711317, 2306.261996161221, 2309.4111001694546, 2246.0454306919655, 2247.7302723673183, 2253.0199363276947, 2254.6054440473017, 2256.4020534558354, 2266.1162146762777, 2271.704678391815, 2274.5223503064376, 2274.886255687209, 2279.908803647852, 8289.792755181168, 8470.534120623362, 8538.914610853873, 8545.01732300787, 8556.443831151017, 5142.434332223453, 5284.841454756363, 5285.973570132163, 5293.206019097128, 5299.470052994709, 3450.0, 3497.9475307870403, 3554.289142171576, 3570.2680950405106, 3571.6964058055096, 1043.1811028342715, 1044.0655613226195, 1050.2018465965843, 1062.5324857062867, 1065.7122576904192, 11203.159763017806, 11230.606928757536, 11239.66281011571, 11254.0, 11290.491927863266, 4326.610605045498, 4367.912641747147, 4484.529124441952, 4485.865424037284, 4487.88780280489, 8924.598393072198, 8933.0, 8953.373263871466, 8960.01439841617, 8963.865542016874, 5099.184130539096, 5107.642465027487, 5111.207762796773, 5111.948880511147, 5141.794328226829, 4285.842822437987, 4437.779610607109, 4612.86161415158, 4641.953580464152, 4759.881002974953, 3655.5796083450214, 3681.815909204529, 3723.3484140275345, 3753.418220175877, 3825.387937929918, 1124.9943750281195, 1127.0138628699888, 1129.203911242572, 1135.5798354608683, 1137.6075254037344, 646.689588997284, 724.619574723264, 817.6647574494428, 861.7630151708205, 977.3402953006693, 5578.776848926079, 5682.602217844711, 5865.328381411277, 5906.49794767441, 5995.190649262294, 927.2303987690201, 956.4744232948436, 957.1146689312498, 979.9608015679293, 987.1461186074138, 3941.3299739044432, 4012.7191096622955, 4020.3567429211184, 4039.6566291864933, 4058.188362327487, 9897.0, 10503.474826258656, 10516.947415263028, 10524.526396312185, 10538.367697938149, 10090.394576325443, 10324.22568307365, 10476.580936762444, 10486.26596138263, 10501.842472362921, 7811.570363629941, 7966.163552827102, 8020.799480013046, 8034.598270086472, 8036.316913062456, 5050.444451110301, 5134.10153223177, 5598.776048958159, 5673.489385955284, 5764.452377024176, 9326.020767819282, 9522.14300712939, 9744.464054476788, 9750.31747777677, 9751.75620609462, 9663.613455461702, 9755.024497550188, 9788.853167202355, 9796.412215267106, 9807.950960245153, 7267.890981635385, 7317.341439270491, 7446.925530744623, 7453.440991925736, 7454.4781865268915, 7963.0, 8061.863606144494, 8068.757937261891, 8070.838583228185, 8102.7569172924905, 6154.706672592992, 7101.11236095488, 7138.964305178441, 7273.636318184175, 7440.813979650552, 7242.167150777728, 7244.818879528054, 7280.781576552712, 7369.77890663281, 7404.148522919933, 6885.449164066762, 6891.793246202622, 6942.687579059056, 6949.0, 6952.374286314256, 4052.5339585947995, 4968.627352948615, 4976.576990955809, 4984.75076246179, 5156.716380599103, 4963.354763880672, 4999.850004499871, 5003.0, 5061.6962982221185, 5141.126008578476], "value": 4437.269429112933, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.1658099544805, 311.2219451371571, 316.1763605806873, 318.98086114833103, 320.8395802098951, 4981.5553489488, 5475.726213689314, 5487.6981766002855, 5538.307711536058, 5557.472040156189, 5019.422766381867, 5373.382061062979, 5380.24676545284, 5411.215373770801, 5490.80782172624, 3838.942415863757, 3848.1326267101663, 3994.380870965005, 4093.631573158415, 4182.16356728654, 3450.3099380124077, 4020.4572382728397, 4076.5719599442077, 4105.876823695294, 4150.564190759972, 4044.716869819114, 4086.6117718816813, 4092.2224777292367, 4094.303968325392, 4108.219438306712, 3413.8975830725117, 3567.0725611340977, 3651.5070465487083, 3837.6546110850018, 4086.366613174948, 3523.859045638197, 3528.012156596157, 3532.1699400640828, 3554.5023696682483, 3571.6606922342335, 5155.432902380735, 5163.3545806774155, 5191.767539957817, 5195.272661827347, 5217.921731174036, 4230.957690423118, 4498.8875278117985, 4533.0, 4696.107739529512, 4809.519048095198, 5325.840224793263, 5604.523615492688, 5627.296587926509, 5632.549396048306, 5641.351244606882, 3934.272159650474, 3956.525216973953, 3974.682025437958, 3976.965988842922, 3989.8005099744896, 5351.678899266057, 5504.422035686235, 5521.585881058937, 5630.296212973379, 5989.401059894019, 3976.8608098716404, 3996.980015099906, 4001.6998725095737, 4005.679545636342, 4011.277969965376, 3464.9307013860084, 3469.462233353834, 3478.530398396235, 3637.3452778500123, 4082.408050832613, 4347.891302717458, 4403.383526306319, 4486.820527178941, 4692.577668009895, 4702.31816386622, 5642.391918303264, 5654.208410822488, 5681.517071049007, 5699.914501282484, 5770.422957704196, 1238.0590751029283, 1355.457816873239, 1375.2642336350004, 1382.8893688504895, 1395.1489591349268, 715.9248278930742, 797.4059325802199, 838.995805020971, 848.7326492154951, 853.9573021348908, 4703.412073490814, 4968.950310496921, 5000.474760246125, 5138.717370544581, 5233.450487698813, 5092.567131793765, 5202.788814753714, 5454.699991500426, 5619.578531610146, 5901.262342207224, 4376.846810361622, 4750.864429476598, 4914.975425122851, 5189.636725429259, 5232.084385232645, 3017.9396412071637, 3042.6348838139124, 3051.649060358043, 3069.8618562164756, 3100.5194194899377, 4950.455449900508, 5653.773849046074, 6093.29927058394, 6123.0, 6186.505079593621, 5166.638335316492, 5347.572194224453, 5365.731713414314, 5599.204823008167, 5955.493783028404, 5204.895902081861, 5380.354357477127, 5690.439302313893, 5904.9332733542915, 6145.8489528665505, 4995.275685025578, 5026.974865125797, 5674.943250567441, 5715.685392359864, 5880.354180408174, 5191.662541934762, 5354.411014788292, 5372.731363431891, 5750.568707347049, 5800.50695690871, 5440.286309812396, 5493.560515158858, 5777.364489906191, 6028.75884964614, 6101.267847858286, 3674.430463278196, 3815.599362066943, 3835.6547910688173, 3870.903227419337, 4002.9399559006056, 4931.334269873521, 5346.545543628679, 5480.91778623313, 5776.0, 5834.978878696126, 5627.7467513961155, 5646.491815736604, 5676.0, 5683.3748287687495, 5698.974184646803, 5369.543588794996, 5376.75766128022, 5406.08071641325, 5642.435756424489, 5649.887002260014, 5210.571014637528, 5274.446183150714, 5560.499555040065, 5568.331800184003, 5570.58220633446, 5334.413214546475, 5494.395616482264, 5550.417206193453, 5669.6314739541795, 5736.483716465538, 4927.408710954708, 5147.536721695065, 5420.810271640474, 5534.612577119563, 5547.8890422192135], "value": 4107.138600129488, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.3484933258733, 768.1300554484567, 782.1787123520302, 828.3207769628902, 843.5782108945527, 15151.545453636394, 15779.368825246991, 15842.524724258263, 15882.047077175377, 15937.76093358599, 14364.712705745878, 14413.351399187035, 14498.710025799477, 14502.202378869184, 14522.636934076654, 713.914330280367, 741.6699568691935, 884.0408157149501, 887.6183241206279, 911.9452832830018, 906.2251774732607, 915.7847905742178, 915.844306467902, 916.9816603667923, 919.3197034194682, 956.5121787888193, 971.9319647624669, 976.2336565795852, 976.3702411944291, 977.8630991661173, 746.6005686957506, 774.6514068669121, 777.3431450424382, 968.7432830299941, 972.9708108756748, 578.8639669677623, 686.2279935073043, 707.678006507038, 708.2987842036398, 710.5843081797154, 12078.879211207863, 14744.483943061941, 14796.260186990716, 14802.40790368384, 14846.554603361916, 13740.450381984809, 13868.035759592778, 13960.581182564438, 13993.180886484794, 14224.786628200585, 11935.94032029843, 12002.879971200264, 12007.0, 12176.452059657337, 12373.505059797597, 4363.051438448178, 4415.094905544388, 4481.686281960264, 4485.183500682229, 4493.393391892086, 101967.47048794274, 102205.48897255541, 102457.9754202456, 104080.47959760229, 108532.91467085386, 11025.558977640965, 11079.667609971553, 11100.833487497694, 11120.777584448428, 11281.323120612626, 21750.89124554367, 21764.34706958762, 21774.91125443753, 21805.127794888027, 21857.890710546344, 8073.233042860918, 8143.226393492609, 8146.307563857138, 8179.591020448954, 8251.711190108317, 4349.652027837766, 4414.713043652152, 4419.11617676466, 4422.402975598317, 4587.747673877969, 3400.5919289684903, 3446.276281980812, 3517.9120521987156, 3524.9295014099575, 3528.7882727035944, 614.8401415631968, 618.1520037764877, 622.1134882792003, 625.7590827531433, 659.36700767263, 527.9894402112013, 589.3369958796354, 599.3377318063564, 606.2028432611103, 684.5687217053325, 8843.0, 9284.489353085644, 9288.256939444827, 9289.628414863464, 9364.859527107099, 517.3533083645443, 532.4489153725875, 587.4888846703395, 601.8865099565855, 658.8122385120217, 3687.686546643565, 4403.625691816167, 4403.8458653946955, 4419.955800442019, 4540.364348991144, 112645.0, 134574.3271283657, 142388.0, 146918.0, 152599.0, 122827.0, 123793.0, 124757.0, 126204.0, 126422.0, 105563.0, 111281.0, 111583.44208278907, 113545.43227283811, 114494.42752786349, 7183.820404489929, 8502.659893604434, 8505.574721264036, 8638.093000235136, 8744.78138046554, 48596.0, 62353.37646623476, 63281.68359158359, 63576.00663927057, 64376.0, 83974.0, 87451.12548874429, 93671.0, 94645.0, 95803.52098239464, 9838.65564705232, 11303.943480282545, 11330.660080197607, 11391.259568128045, 11886.465109069939, 12074.879251207727, 12118.454669540068, 12201.20692155007, 12259.448324825582, 12549.560765373168, 11520.827187592025, 11683.591074312359, 11806.704832379259, 11826.053915686896, 12154.453049612965, 11889.0, 11901.10741694359, 11953.402329883646, 12200.511979521074, 12262.877371226174, 11412.258203216765, 11606.361650109326, 11678.88321116778, 11712.70718232051, 11929.582464613697, 9681.37071090377, 9939.950300248453, 9978.25163112784, 10290.639827605997, 10319.535620896928, 9373.20327772147, 9756.21950243993, 9845.360051596623, 9891.540992732915, 10040.347377420445], "value": 7568.781280007817, "unit": "score"}], "name": "dromaeo_css", "value": 7203.8569963238415, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 106.0, 90.0, 155.0, 164.0, 166.0, 134.0, 112.0, 113.0, 111.0], "value": 126.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 144.0, 110.0, 144.0, 142.0, 146.0, 164.0, 135.0, 173.0, 141.0], "value": 146.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 185.0, 183.0, 182.0, 187.0, 183.0, 182.0, 186.0, 184.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 74.0, 58.0, 87.0, 59.0, 57.0, 65.0, 65.0, 102.0, 78.0], "value": 72.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 90.0, 93.0, 72.0, 72.0, 69.0, 97.0, 71.0, 68.0, 105.0], "value": 82.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 86.0, 89.0, 86.0, 89.0, 89.0, 134.0, 107.0], "value": 94.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 114.0, 149.0, 149.0], "value": 107.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 87.0, 95.0, 106.0, 86.0, 84.0, 88.0, 86.0], "value": 88.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 56.0, 56.0, 57.0, 55.0, 55.0, 55.0, 55.0, 56.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 83.0, 74.0, 72.0, 69.0, 72.0, 75.0, 71.0, 72.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 87.0, 86.0, 88.0, 87.0, 88.0, 89.0, 147.0, 86.0], "value": 93.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 132.0, 143.0, 140.0, 143.0, 139.0, 137.0, 138.0, 149.0, 142.0], "value": 140.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 228.0, 224.0, 163.0, 143.0, 190.0, 227.0, 152.0, 232.0, 230.0], "value": 195.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 68.0, 67.0, 66.0, 66.0, 67.0, 67.0, 66.0, 66.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1524.9999999999998, "alertThreshold": 2.0}]} 11:52:42 INFO - Return code: 0 11:52:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:52:43 ERROR - # TBPL SUCCESS # 11:52:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:52:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:52:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:52:43 INFO - 2016-06-02 11:52:43.022 screenresolution[1969:19820] starting screenresolution argv=screenresolution get 11:52:43 INFO - 2016-06-02 11:52:43.025 screenresolution[1969:19820] Display 0: 1600x1200x32@60 11:52:43 INFO - 2016-06-02 11:52:43.030 screenresolution[1970:19824] starting screenresolution argv=screenresolution list 11:52:43 INFO - Available Modes on Display 0 11:52:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:52:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:52:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:52:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:52:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:52:43 INFO - Intel Iris: 11:52:43 INFO - Chipset Model: Intel Iris 11:52:43 INFO - Type: GPU 11:52:43 INFO - Bus: Built-In 11:52:43 INFO - VRAM (Dynamic, Max): 1536 MB 11:52:43 INFO - Vendor: Intel (0x8086) 11:52:43 INFO - Device ID: 0x0a2e 11:52:43 INFO - Revision ID: 0x0009 11:52:43 INFO - Displays: 11:52:43 INFO - Display: 11:52:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:52:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:52:43 INFO - Display Serial Number: 1600x1200 60 11:52:43 INFO - Main Display: Yes 11:52:43 INFO - Mirror: Off 11:52:43 INFO - Online: Yes 11:52:43 INFO - Rotation: Supported 11:52:43 INFO - Return code: 0 11:52:43 INFO - Running post-action listener: _resource_record_post_action 11:52:43 INFO - ##### 11:52:43 INFO - ##### Finished run-tests step (success) 11:52:43 INFO - ##### 11:52:43 INFO - Running post-run listener: _resource_record_post_run 11:52:43 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 688128; Write bytes: 699573760; Read time: 8; Write time: 1944 11:52:43 INFO - TinderboxPrint: CPU usage
53.8% 11:52:43 INFO - TinderboxPrint: I/O read bytes / time
688,128 / 8 11:52:43 INFO - TinderboxPrint: I/O write bytes / time
699,573,760 / 1,944 11:52:43 INFO - TinderboxPrint: CPU idle
2,241.6 (46.2%) 11:52:43 INFO - TinderboxPrint: CPU system
968.6 (20.0%) 11:52:43 INFO - TinderboxPrint: CPU user
1,639.5 (33.8%) 11:52:43 INFO - TinderboxPrint: Swap in / out
400,228,352 / 0 11:52:43 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 391242240; Write bytes: 385291776; Read time: 29737; Write time: 792 11:52:43 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 688128; Write bytes: 296714240; Read time: 8; Write time: 1116 11:52:43 INFO - Running post-run listener: _upload_blobber_files 11:52:43 INFO - Blob upload gear active. 11:52:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:52:43 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 11:52:43 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'] 11:52:43 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 11:52:44 INFO - (blobuploader) - INFO - Open directory for files ... 11:52:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:52:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:52:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:52:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:52:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:52:45 INFO - (blobuploader) - INFO - Done attempting. 11:52:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:52:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:52:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:52:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:52:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:52:48 INFO - (blobuploader) - INFO - Done attempting. 11:52:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:52:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:52:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:52:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:52:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:52:51 INFO - (blobuploader) - INFO - Done attempting. 11:52:51 INFO - (blobuploader) - INFO - Iteration through files over. 11:52:51 INFO - Return code: 0 11:52:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:52:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:52:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/10934d8fee5de6590096d88ff85cfdd58b6b639e204436d1e0578941104301b55807753f8fb38c8b0969a70df87629f8626d1d7aecf13db59cd805fef9083b5b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b906d5954db20c5c32ceddb44d99a59f549032ac1a499715ac428633346f37599c8ea31bdd161e13314f2af5d8527adc41b7b6efa15ba224b54c0f0d50099ac9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/33e0f4a4851ec582e369830106efcd96127437056cab4a1bfce7b918e665b8f3b085ecd125a35e596c7626f03f1ade68e0e9892e114fe4ae6d9d46689c5bc9ca"} 11:52:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:52:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:52:51 INFO - Contents: 11:52:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/10934d8fee5de6590096d88ff85cfdd58b6b639e204436d1e0578941104301b55807753f8fb38c8b0969a70df87629f8626d1d7aecf13db59cd805fef9083b5b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b906d5954db20c5c32ceddb44d99a59f549032ac1a499715ac428633346f37599c8ea31bdd161e13314f2af5d8527adc41b7b6efa15ba224b54c0f0d50099ac9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/33e0f4a4851ec582e369830106efcd96127437056cab4a1bfce7b918e665b8f3b085ecd125a35e596c7626f03f1ade68e0e9892e114fe4ae6d9d46689c5bc9ca"} 11:52:51 INFO - Running post-run listener: copy_logs_to_upload_dir 11:52:51 INFO - Copying logs to upload dir... 11:52:51 INFO - mkdir: /builds/slave/test/build/upload/logs 11:52:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1295.345798 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 35 secs) (at 2016-06-02 11:52:51.608971) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 11:52:51.612616) ========= 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.r2btpFwnF0/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.KlVFz3X0iC/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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/10934d8fee5de6590096d88ff85cfdd58b6b639e204436d1e0578941104301b55807753f8fb38c8b0969a70df87629f8626d1d7aecf13db59cd805fef9083b5b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b906d5954db20c5c32ceddb44d99a59f549032ac1a499715ac428633346f37599c8ea31bdd161e13314f2af5d8527adc41b7b6efa15ba224b54c0f0d50099ac9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/33e0f4a4851ec582e369830106efcd96127437056cab4a1bfce7b918e665b8f3b085ecd125a35e596c7626f03f1ade68e0e9892e114fe4ae6d9d46689c5bc9ca"} build_url:https://queue.taskcluster.net/v1/task/FtS4x_yTRZSR8NO1zv4apg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011368 build_url: 'https://queue.taskcluster.net/v1/task/FtS4x_yTRZSR8NO1zv4apg/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/10934d8fee5de6590096d88ff85cfdd58b6b639e204436d1e0578941104301b55807753f8fb38c8b0969a70df87629f8626d1d7aecf13db59cd805fef9083b5b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b906d5954db20c5c32ceddb44d99a59f549032ac1a499715ac428633346f37599c8ea31bdd161e13314f2af5d8527adc41b7b6efa15ba224b54c0f0d50099ac9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/33e0f4a4851ec582e369830106efcd96127437056cab4a1bfce7b918e665b8f3b085ecd125a35e596c7626f03f1ade68e0e9892e114fe4ae6d9d46689c5bc9ca"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 11:52:51.649790) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 11:52:51.650324) ========= 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.r2btpFwnF0/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.KlVFz3X0iC/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/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.005383 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 11:52:51.701343) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 11:52:51.701874) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 11:52:51.703179) ========= ========= Total master_lag: 0.30 =========