builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0235 starttime: 1464872905.11 results: success (0) buildid: 20160602041745 builduid: 49a634aa51964cb9bb12dfaa5cefdd4a revision: e74192b1a9c82ebcb431e5b4c28775a8111c8864 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 06:08:25.108757) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 06:08:25.109293) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 06:08:25.109616) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 06:08:25.131752) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 06:08:25.132146) ========= 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.SM8W7GrC5K/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.vrfyYoThsp/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:08:25-- 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.34M=0.001s 2016-06-02 06:08:25 (8.34 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.095447 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 06:08:25.258338) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 06:08:25.258692) ========= 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.SM8W7GrC5K/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.vrfyYoThsp/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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.020207 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 06:08:25.307636) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-02 06:08:25.308021) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e74192b1a9c82ebcb431e5b4c28775a8111c8864 --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 e74192b1a9c82ebcb431e5b4c28775a8111c8864 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SM8W7GrC5K/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.vrfyYoThsp/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:08:25,388 truncating revision to first 12 chars 2016-06-02 06:08:25,388 Setting DEBUG logging. 2016-06-02 06:08:25,389 attempt 1/10 2016-06-02 06:08:25,389 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e74192b1a9c8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 06:08:27,231 unpacking tar archive at: fx-team-e74192b1a9c8/testing/mozharness/ program finished with exit code 0 elapsedTime=2.109867 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-02 06:08:27.440405) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 06:08:27.440979) ========= script_repo_revision: e74192b1a9c82ebcb431e5b4c28775a8111c8864 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 06:08:27.442019) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 06:08:27.442535) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 06:08:27.460193) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-06-02 06:08:27.460600) ========= /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.SM8W7GrC5K/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.vrfyYoThsp/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:08:27 INFO - MultiFileLogger online at 20160602 06:08:27 in /builds/slave/test 06:08:27 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 06:08:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:08:27 INFO - {'append_to_log': False, 06:08:27 INFO - 'base_work_dir': '/builds/slave/test', 06:08:27 INFO - 'blob_upload_branch': 'Fx-Team', 06:08:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:08:27 INFO - 'branch': 'Fx-Team', 06:08:27 INFO - 'buildbot_json_path': 'buildprops.json', 06:08:27 INFO - 'config_files': ('talos/mac_config.py',), 06:08:27 INFO - 'default_actions': ('clobber', 06:08:27 INFO - 'read-buildbot-config', 06:08:27 INFO - 'download-and-extract', 06:08:27 INFO - 'populate-webroot', 06:08:27 INFO - 'create-virtualenv', 06:08:27 INFO - 'install', 06:08:27 INFO - 'run-tests'), 06:08:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:08:27 INFO - 'download_minidump_stackwalk': True, 06:08:27 INFO - 'download_symbols': 'ondemand', 06:08:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:08:27 INFO - 'tooltool.py': '/tools/tooltool.py', 06:08:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:08:27 INFO - '/tools/misc-python/virtualenv.py')}, 06:08:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:08:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:08:27 INFO - 'installer_path': 'installer.exe', 06:08:27 INFO - 'log_level': 'info', 06:08:27 INFO - 'log_name': 'talos', 06:08:27 INFO - 'log_to_console': True, 06:08:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:08:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:08:27 INFO - 'opt_config_files': (), 06:08:27 INFO - 'pip_index': False, 06:08:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:08:27 INFO - 'cmd': ('bash', 06:08:27 INFO - '-c', 06:08:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:08:27 INFO - 'enabled': True, 06:08:27 INFO - 'halt_on_failure': False, 06:08:27 INFO - 'name': 'check_screen_resolution'},), 06:08:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:08:27 INFO - 'cmd': ('bash', 06:08:27 INFO - '-c', 06:08:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:08:27 INFO - 'enabled': True, 06:08:27 INFO - 'halt_on_failure': False, 06:08:27 INFO - 'name': 'check_screen_resolution'},), 06:08:27 INFO - 'run_cmd_checks_enabled': True, 06:08:27 INFO - 'sps_profile': False, 06:08:27 INFO - 'sps_profile_interval': 0, 06:08:27 INFO - 'suite': 'dromaeojs', 06:08:27 INFO - 'system_bits': '32', 06:08:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:08:27 INFO - 'title': 't-yosemite-r7-0235', 06:08:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:08:27 INFO - 'use_talos_json': True, 06:08:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:08:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:08:27 INFO - 'work_dir': 'build'} 06:08:27 INFO - ##### 06:08:27 INFO - ##### Running clobber step. 06:08:27 INFO - ##### 06:08:27 INFO - Running pre-action listener: _resource_record_pre_action 06:08:27 INFO - Running main action method: clobber 06:08:27 INFO - rmtree: /builds/slave/test/build 06:08:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:08:28 INFO - Running post-action listener: _resource_record_post_action 06:08:28 INFO - ##### 06:08:28 INFO - ##### Finished clobber step (success) 06:08:28 INFO - ##### 06:08:28 INFO - ##### 06:08:28 INFO - ##### Running read-buildbot-config step. 06:08:28 INFO - ##### 06:08:28 INFO - Running pre-action listener: _resource_record_pre_action 06:08:28 INFO - Running main action method: read_buildbot_config 06:08:28 INFO - Using buildbot properties: 06:08:28 INFO - { 06:08:28 INFO - "project": "", 06:08:28 INFO - "product": "firefox", 06:08:28 INFO - "script_repo_revision": "production", 06:08:28 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 06:08:28 INFO - "repository": "", 06:08:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 06:08:28 INFO - "buildid": "20160602041745", 06:08:28 INFO - "pgo_build": "False", 06:08:28 INFO - "basedir": "/builds/slave/test", 06:08:28 INFO - "buildnumber": 44, 06:08:28 INFO - "slavename": "t-yosemite-r7-0235", 06:08:28 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 06:08:28 INFO - "platform": "yosemite_r7", 06:08:28 INFO - "scheduler": "tests-fx-team-macosx64-talos", 06:08:28 INFO - "branch": "fx-team", 06:08:28 INFO - "repo_path": "integration/fx-team", 06:08:28 INFO - "revision": "e74192b1a9c82ebcb431e5b4c28775a8111c8864", 06:08:28 INFO - "stage_platform": "macosx64", 06:08:28 INFO - "builduid": "49a634aa51964cb9bb12dfaa5cefdd4a", 06:08:28 INFO - "slavebuilddir": "test" 06:08:28 INFO - } 06:08:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 06:08:28 INFO - Running post-action listener: _resource_record_post_action 06:08:28 INFO - ##### 06:08:28 INFO - ##### Finished read-buildbot-config step (success) 06:08:28 INFO - ##### 06:08:28 INFO - ##### 06:08:28 INFO - ##### Running download-and-extract step. 06:08:28 INFO - ##### 06:08:28 INFO - Running pre-action listener: _resource_record_pre_action 06:08:28 INFO - Running main action method: download_and_extract 06:08:28 INFO - mkdir: /builds/slave/test/build/tests 06:08: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')]} 06:08:28 INFO - https://queue.taskcluster.net/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:08:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 06:08:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 06:08:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/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 06:08:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/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 06:08:29 INFO - Downloaded 1199 bytes. 06:08:29 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 06:08:29 INFO - Using the following test package requirements: 06:08:29 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 06:08:29 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:08:29 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 06:08:29 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:08:29 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 06:08:29 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:08:29 INFO - u'jsshell-mac.zip'], 06:08:29 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:08:29 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 06:08:29 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 06:08:29 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:08:29 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 06:08:29 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:08:29 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 06:08:29 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:08:29 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 06:08:29 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:08:29 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 06:08:29 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:08:29 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')]} 06:08:29 INFO - https://queue.taskcluster.net/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:08:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:08:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:08:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/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 06:08:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/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 06:08:32 INFO - Downloaded 18713568 bytes. 06:08:32 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'] 06:08:32 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 06:08:35 INFO - Return code: 0 06:08:35 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 06:08:35 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')]} 06:08:35 INFO - https://queue.taskcluster.net/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:08:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:08:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:08:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/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 06:08:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/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 06:08:40 INFO - Downloaded 18713568 bytes. 06:08: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'] 06:08: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 06:08:44 INFO - Return code: 0 06:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:08:44 INFO - https://queue.taskcluster.net/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:08:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 06:08:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 06:08:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/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 06:08:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/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 06:08:45 INFO - Downloaded 10834055 bytes. 06:08:45 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'] 06:08:45 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 06:08:46 INFO - Return code: 0 06:08:46 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')]} 06:08:46 INFO - https://queue.taskcluster.net/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 06:08:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:08:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:08:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 06:08:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fvsdsXvkQ1KakihQreZc7Q/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 06:08:51 INFO - Downloaded 111074126 bytes. 06:08:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:08:51 INFO - mkdir: /builds/slave/test/properties 06:08:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:08:51 INFO - Writing to file /builds/slave/test/properties/build_url 06:08:51 INFO - Contents: 06:08:51 INFO - build_url:https://queue.taskcluster.net/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:08:53 INFO - Running post-action listener: _resource_record_post_action 06:08:53 INFO - Running post-action listener: set_extra_try_arguments 06:08:53 INFO - ##### 06:08:53 INFO - ##### Finished download-and-extract step (success) 06:08:53 INFO - ##### 06:08:53 INFO - ##### 06:08:53 INFO - ##### Running populate-webroot step. 06:08:53 INFO - ##### 06:08:53 INFO - Running pre-action listener: _resource_record_pre_action 06:08:53 INFO - Running main action method: populate_webroot 06:08:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:08:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:08:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:08:53 INFO - '--tppagecycles', 06:08:53 INFO - '7'], 06:08:53 INFO - 'tests': ['tsvgm']}}, 06:08:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:08:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:08:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:08:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:08:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:08:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:08:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:08:53 INFO - 'talos_options': ['--disable-e10s'], 06:08:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:08:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:08:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:08:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:08:53 INFO - 'talos_options': ['--disable-e10s'], 06:08:53 INFO - 'tests': ['damp', 'tps']}, 06:08:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:08:53 INFO - 'tests': ['damp', 'tps']}, 06:08:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:08:53 INFO - 'tests': ['dromaeo_dom']}, 06:08:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:08:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:08:53 INFO - 'tests': ['a11yr', 06:08:53 INFO - 'ts_paint', 06:08:53 INFO - 'tpaint', 06:08:53 INFO - 'sessionrestore', 06:08:53 INFO - 'sessionrestore_no_auto_restore', 06:08:53 INFO - 'tabpaint']}, 06:08:53 INFO - 'other-e10s': {'tests': ['a11yr', 06:08:53 INFO - 'ts_paint', 06:08:53 INFO - 'tpaint', 06:08:53 INFO - 'sessionrestore', 06:08:53 INFO - 'sessionrestore_no_auto_restore', 06:08:53 INFO - 'tabpaint']}, 06:08:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:08:53 INFO - 'ts_paint', 06:08:53 INFO - 'tpaint', 06:08:53 INFO - 'sessionrestore', 06:08:53 INFO - 'sessionrestore_no_auto_restore', 06:08:53 INFO - 'tabpaint']}, 06:08:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:08:53 INFO - 'ts_paint', 06:08:53 INFO - 'tpaint', 06:08:53 INFO - 'sessionrestore', 06:08:53 INFO - 'sessionrestore_no_auto_restore', 06:08:53 INFO - 'tabpaint']}, 06:08:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:08:53 INFO - 'tests': ['a11yr', 06:08:53 INFO - 'ts_paint', 06:08:53 INFO - 'tpaint', 06:08:53 INFO - 'sessionrestore', 06:08:53 INFO - 'sessionrestore_no_auto_restore', 06:08:53 INFO - 'tabpaint']}, 06:08:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:08:53 INFO - 'tests': ['a11yr', 06:08:53 INFO - 'ts_paint', 06:08:53 INFO - 'tpaint', 06:08:53 INFO - 'sessionrestore', 06:08:53 INFO - 'sessionrestore_no_auto_restore', 06:08:53 INFO - 'tabpaint']}, 06:08:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:08:53 INFO - 'tests': ['tsvgx', 06:08:53 INFO - 'tsvgr_opacity', 06:08:53 INFO - 'tart', 06:08:53 INFO - 'tscrollx', 06:08:53 INFO - 'cart']}, 06:08:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:08:53 INFO - 'tsvgr_opacity', 06:08:53 INFO - 'tart', 06:08:53 INFO - 'tscrollx', 06:08:53 INFO - 'cart']}, 06:08:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:08:53 INFO - 'talos_options': ['--disable-e10s'], 06:08:53 INFO - 'tests': ['tp5o']}, 06:08:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:08:53 INFO - 'tests': ['tp5o']}, 06:08:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:08:53 INFO - 'talos_options': ['--disable-e10s', 06:08:53 INFO - '--xperf_path', 06:08:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:08:53 INFO - 'tests': ['tp5n']}, 06:08:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:08:53 INFO - 'talos_options': ['--xperf_path', 06:08:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:08:53 INFO - 'tests': ['tp5n']}}, 06:08:53 INFO - 'talos.zip': {'path': '', 06:08:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:08:53 INFO - Running post-action listener: _resource_record_post_action 06:08:53 INFO - ##### 06:08:53 INFO - ##### Finished populate-webroot step (success) 06:08:53 INFO - ##### 06:08:53 INFO - ##### 06:08:53 INFO - ##### Running create-virtualenv step. 06:08:53 INFO - ##### 06:08:53 INFO - Running pre-action listener: _resource_record_pre_action 06:08:53 INFO - Running main action method: create_virtualenv 06:08:53 INFO - Creating virtualenv /builds/slave/test/build/venv 06:08:53 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:08:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:08:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:08:53 INFO - Using real prefix '/tools/python27' 06:08:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:08:53 INFO - Installing distribute.............................................................................................................................................................................................done. 06:08:56 INFO - Installing pip.................done. 06:08:56 INFO - Return code: 0 06:08:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:08:56 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')]} 06:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:08:56 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')]} 06:08:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:08:56 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 0x103a75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a7b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccd8596f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103919d30>, '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 0x10373ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103910b20>, '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.SM8W7GrC5K/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.vrfyYoThsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:08:56 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 06:08:56 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 06:08:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM8W7GrC5K/Render', 06:08:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:56 INFO - 'HOME': '/Users/cltbld', 06:08:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:56 INFO - 'LOGNAME': 'cltbld', 06:08:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:56 INFO - 'MOZ_NO_REMOTE': '1', 06:08:56 INFO - 'NO_EM_RESTART': '1', 06:08:56 INFO - 'PAGER': '/bin/cat', 06:08:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:56 INFO - 'PWD': '/builds/slave/test', 06:08:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:56 INFO - 'SHELL': '/bin/bash', 06:08:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrfyYoThsp/Listeners', 06:08:56 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:08:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:56 INFO - 'USER': 'cltbld', 06:08:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:56 INFO - 'XPC_FLAGS': '0x0', 06:08:56 INFO - 'XPC_SERVICE_NAME': '0', 06:08:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:57 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 06:08:57 INFO - Cleaning up... 06:08:57 INFO - Return code: 0 06:08:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:08:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:08:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:08:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a7b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccd8596f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103919d30>, '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 0x10373ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103910b20>, '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.SM8W7GrC5K/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.vrfyYoThsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:08:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:08:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:08:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM8W7GrC5K/Render', 06:08:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:08:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:08:57 INFO - 'HOME': '/Users/cltbld', 06:08:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:08:57 INFO - 'LOGNAME': 'cltbld', 06:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:08:57 INFO - 'MOZ_NO_REMOTE': '1', 06:08:57 INFO - 'NO_EM_RESTART': '1', 06:08:57 INFO - 'PAGER': '/bin/cat', 06:08:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:08:57 INFO - 'PWD': '/builds/slave/test', 06:08:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:08:57 INFO - 'SHELL': '/bin/bash', 06:08:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrfyYoThsp/Listeners', 06:08:57 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:08:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:08:57 INFO - 'USER': 'cltbld', 06:08:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:08:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:08:57 INFO - 'XPC_FLAGS': '0x0', 06:08:57 INFO - 'XPC_SERVICE_NAME': '0', 06:08:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:08:57 INFO - Downloading/unpacking psutil>=3.1.1 06:08:57 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 06:08:57 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 06:08:57 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 06:08:57 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 06:08:57 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 06:08:57 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 06:09:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:09:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:09:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:09:00 INFO - Installing collected packages: psutil 06:09:00 INFO - Running setup.py install for psutil 06:09:00 INFO - building 'psutil._psutil_osx' extension 06:09:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 06:09:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 06:09:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 06:09:00 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 06:09:00 INFO - building 'psutil._psutil_posix' extension 06:09:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 06:09:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:09:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:09:00 INFO - ^ 06:09:00 INFO - 1 warning generated. 06:09:00 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 06:09:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:09:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:09:01 INFO - Successfully installed psutil 06:09:01 INFO - Cleaning up... 06:09:01 INFO - Return code: 0 06:09:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:09:01 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')]} 06:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:09:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:01 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')]} 06:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:09:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:01 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 0x103a75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a7b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccd8596f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103919d30>, '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 0x10373ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103910b20>, '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.SM8W7GrC5K/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.vrfyYoThsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:09:01 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 06:09:01 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 06:09:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM8W7GrC5K/Render', 06:09:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:09:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:09:01 INFO - 'HOME': '/Users/cltbld', 06:09:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:09:01 INFO - 'LOGNAME': 'cltbld', 06:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:09:01 INFO - 'MOZ_NO_REMOTE': '1', 06:09:01 INFO - 'NO_EM_RESTART': '1', 06:09:01 INFO - 'PAGER': '/bin/cat', 06:09:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:09:01 INFO - 'PWD': '/builds/slave/test', 06:09:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:09:01 INFO - 'SHELL': '/bin/bash', 06:09:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrfyYoThsp/Listeners', 06:09:01 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:09:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:09:01 INFO - 'USER': 'cltbld', 06:09:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:09:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:09:01 INFO - 'XPC_FLAGS': '0x0', 06:09:01 INFO - 'XPC_SERVICE_NAME': '0', 06:09:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:09:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:09:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:09:03 INFO - Installing collected packages: mozsystemmonitor 06:09:03 INFO - Running setup.py install for mozsystemmonitor 06:09:04 INFO - Successfully installed mozsystemmonitor 06:09:04 INFO - Cleaning up... 06:09:04 INFO - Return code: 0 06:09:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:09: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')]} 06:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:09:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09: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')]} 06:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:09:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09: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', '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 0x103a75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a7b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccd8596f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103919d30>, '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 0x10373ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103910b20>, '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.SM8W7GrC5K/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.vrfyYoThsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:09: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:09: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 blobuploader==1.2.4 06:09:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM8W7GrC5K/Render', 06:09:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:09:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:09:04 INFO - 'HOME': '/Users/cltbld', 06:09:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:09:04 INFO - 'LOGNAME': 'cltbld', 06:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:09:04 INFO - 'MOZ_NO_REMOTE': '1', 06:09:04 INFO - 'NO_EM_RESTART': '1', 06:09:04 INFO - 'PAGER': '/bin/cat', 06:09:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:09:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:09:04 INFO - 'PWD': '/builds/slave/test', 06:09:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:09:04 INFO - 'SHELL': '/bin/bash', 06:09:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrfyYoThsp/Listeners', 06:09:04 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:09:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:09:04 INFO - 'USER': 'cltbld', 06:09:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:09:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:09:04 INFO - 'XPC_FLAGS': '0x0', 06:09:04 INFO - 'XPC_SERVICE_NAME': '0', 06:09:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:09:04 INFO - Downloading/unpacking blobuploader==1.2.4 06:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:06 INFO - Downloading blobuploader-1.2.4.tar.gz 06:09:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:09:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:09:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:07 INFO - Downloading docopt-0.6.1.tar.gz 06:09:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:09:08 INFO - Installing collected packages: blobuploader, requests, docopt 06:09:08 INFO - Running setup.py install for blobuploader 06:09:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:09:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:09:08 INFO - Running setup.py install for requests 06:09:08 INFO - Running setup.py install for docopt 06:09:08 INFO - Successfully installed blobuploader requests docopt 06:09:08 INFO - Cleaning up... 06:09:08 INFO - Return code: 0 06:09:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:09:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:09:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:09:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:09:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a7b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccd8596f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103919d30>, '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 0x10373ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103910b20>, '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.SM8W7GrC5K/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.vrfyYoThsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:09:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:09:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:09:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM8W7GrC5K/Render', 06:09:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:09:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:09:08 INFO - 'HOME': '/Users/cltbld', 06:09:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:09:08 INFO - 'LOGNAME': 'cltbld', 06:09:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:09:08 INFO - 'MOZ_NO_REMOTE': '1', 06:09:08 INFO - 'NO_EM_RESTART': '1', 06:09:08 INFO - 'PAGER': '/bin/cat', 06:09:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:09:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:09:08 INFO - 'PWD': '/builds/slave/test', 06:09:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:09:08 INFO - 'SHELL': '/bin/bash', 06:09:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrfyYoThsp/Listeners', 06:09:08 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:09:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:09:08 INFO - 'USER': 'cltbld', 06:09:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:09:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:09:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:09:08 INFO - 'XPC_FLAGS': '0x0', 06:09:08 INFO - 'XPC_SERVICE_NAME': '0', 06:09:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:09:09 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-KjYIkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:09:09 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-_vAWJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:09:09 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-zyT09N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:09:09 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-ipwvCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:09:09 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-7PQF8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:09:09 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-47UjMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:09:09 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-6muYN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:09:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:09:09 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-yWZvYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:09:10 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-olLoSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:09:10 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-6EFI4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:09:10 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-fTAl99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:09:10 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-CV66wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:09:10 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-6iAxcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:09:10 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-03R9SD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:09:10 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-4bkJCZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:09:10 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-x5X3LC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:09:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:09:10 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-QNkA4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:09:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:09:10 INFO - Running setup.py install for manifestparser 06:09:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:09:11 INFO - Running setup.py install for mozcrash 06:09:11 INFO - Running setup.py install for mozdebug 06:09:11 INFO - Running setup.py install for mozdevice 06:09:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:09:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:09:11 INFO - Running setup.py install for mozfile 06:09:11 INFO - Running setup.py install for mozhttpd 06:09:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:09:11 INFO - Running setup.py install for mozinfo 06:09:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:09:12 INFO - Running setup.py install for mozInstall 06:09:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:09:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:09:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:09:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:09:12 INFO - Running setup.py install for mozleak 06:09:12 INFO - Running setup.py install for mozlog 06:09:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:09:12 INFO - Running setup.py install for moznetwork 06:09:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:09:12 INFO - Running setup.py install for mozprocess 06:09:12 INFO - Running setup.py install for mozprofile 06:09:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:09:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:09:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:09:12 INFO - Running setup.py install for mozrunner 06:09:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:09:13 INFO - Running setup.py install for mozscreenshot 06:09:13 INFO - Running setup.py install for moztest 06:09:13 INFO - Running setup.py install for mozversion 06:09:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:09:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:09:13 INFO - Cleaning up... 06:09:13 INFO - Return code: 0 06:09:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:09:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:09:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a7b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccd8596f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103919d30>, '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 0x10373ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103910b20>, '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.SM8W7GrC5K/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.vrfyYoThsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:09:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:09:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:09:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM8W7GrC5K/Render', 06:09:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:09:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:09:13 INFO - 'HOME': '/Users/cltbld', 06:09:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:09:13 INFO - 'LOGNAME': 'cltbld', 06:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:09:13 INFO - 'MOZ_NO_REMOTE': '1', 06:09:13 INFO - 'NO_EM_RESTART': '1', 06:09:13 INFO - 'PAGER': '/bin/cat', 06:09:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:09:13 INFO - 'PWD': '/builds/slave/test', 06:09:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:09:13 INFO - 'SHELL': '/bin/bash', 06:09:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrfyYoThsp/Listeners', 06:09:13 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:09:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:09:13 INFO - 'USER': 'cltbld', 06:09:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:09:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:09:13 INFO - 'XPC_FLAGS': '0x0', 06:09:13 INFO - 'XPC_SERVICE_NAME': '0', 06:09:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:09:13 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-xh87h6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:09:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:09:13 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-JkOGIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:09:14 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)) 06:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:09:14 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-af99Nr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:09:14 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)) 06:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:09:14 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-HaR9My-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:09:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:09:14 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-TIFCqz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:09:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 06:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:09:14 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-svfgDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:09:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 06:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:09:14 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-9BPass-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:09:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:09:14 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-widwMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:09:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:09:14 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-KNLG5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:09:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:09:14 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-ESpAr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:09:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:09:14 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-YxCUt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:09:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:09:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:09:15 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-eDeeNj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:09:15 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)) 06:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:09:15 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-Zbb7Vt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:09:15 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)) 06:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:09:15 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-aN43NF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:09:15 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-q8DWTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 06:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:09:15 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-lqheQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:09:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:09:15 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-DhMEkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 06:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:09:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:18 INFO - Downloading blessings-1.6.tar.gz 06:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:09:18 INFO - Installing collected packages: blessings 06:09:18 INFO - Running setup.py install for blessings 06:09:18 INFO - Successfully installed blessings 06:09:18 INFO - Cleaning up... 06:09:18 INFO - Return code: 0 06:09:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:09:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:09:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:09:18 INFO - Reading from file tmpfile_stdout 06:09:18 INFO - Current package versions: 06:09:18 INFO - blessings == 1.6 06:09:18 INFO - blobuploader == 1.2.4 06:09:18 INFO - docopt == 0.6.1 06:09:18 INFO - manifestparser == 1.1 06:09:18 INFO - mozInstall == 1.12 06:09:18 INFO - mozcrash == 0.17 06:09:18 INFO - mozdebug == 0.1 06:09:18 INFO - mozdevice == 0.48 06:09:18 INFO - mozfile == 1.2 06:09:18 INFO - mozhttpd == 0.7 06:09:18 INFO - mozinfo == 0.9 06:09:18 INFO - mozleak == 0.1 06:09:18 INFO - mozlog == 3.2 06:09:18 INFO - moznetwork == 0.27 06:09:18 INFO - mozprocess == 0.22 06:09:18 INFO - mozprofile == 0.28 06:09:18 INFO - mozrunner == 6.11 06:09:18 INFO - mozscreenshot == 0.1 06:09:18 INFO - mozsystemmonitor == 0.3 06:09:18 INFO - moztest == 0.7 06:09:18 INFO - mozversion == 1.4 06:09:18 INFO - psutil == 3.1.1 06:09:18 INFO - requests == 1.2.3 06:09:18 INFO - wsgiref == 0.1.2 06:09:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:18 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 0x103a75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a7b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccd8596f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103919d30>, '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 0x10373ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103910b20>, '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.SM8W7GrC5K/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.vrfyYoThsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:09:18 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 06:09:18 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 06:09:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM8W7GrC5K/Render', 06:09:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:09:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:09:18 INFO - 'HOME': '/Users/cltbld', 06:09:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:09:18 INFO - 'LOGNAME': 'cltbld', 06:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:09:18 INFO - 'MOZ_NO_REMOTE': '1', 06:09:18 INFO - 'NO_EM_RESTART': '1', 06:09:18 INFO - 'PAGER': '/bin/cat', 06:09:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:09:18 INFO - 'PWD': '/builds/slave/test', 06:09:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:09:18 INFO - 'SHELL': '/bin/bash', 06:09:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrfyYoThsp/Listeners', 06:09:18 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:09:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:09:18 INFO - 'USER': 'cltbld', 06:09:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:09:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:09:18 INFO - 'XPC_FLAGS': '0x0', 06:09:18 INFO - 'XPC_SERVICE_NAME': '0', 06:09:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:09:18 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)) 06:09:18 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)) 06:09:18 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)) 06:09:18 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)) 06:09:18 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)) 06:09:18 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)) 06:09:18 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)) 06:09:18 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)) 06:09:18 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)) 06:09:18 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)) 06:09:18 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)) 06:09:18 INFO - Cleaning up... 06:09:18 INFO - Return code: 0 06:09:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09:18 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 0x103a75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a7b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccd8596f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103919d30>, '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 0x10373ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103910b20>, '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.SM8W7GrC5K/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.vrfyYoThsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:09:18 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 06:09:18 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 06:09:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM8W7GrC5K/Render', 06:09:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:09:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:09:18 INFO - 'HOME': '/Users/cltbld', 06:09:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:09:18 INFO - 'LOGNAME': 'cltbld', 06:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:09:18 INFO - 'MOZ_NO_REMOTE': '1', 06:09:18 INFO - 'NO_EM_RESTART': '1', 06:09:18 INFO - 'PAGER': '/bin/cat', 06:09:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:09:18 INFO - 'PWD': '/builds/slave/test', 06:09:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:09:18 INFO - 'SHELL': '/bin/bash', 06:09:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrfyYoThsp/Listeners', 06:09:18 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:09:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:09:18 INFO - 'USER': 'cltbld', 06:09:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:09:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:09:18 INFO - 'XPC_FLAGS': '0x0', 06:09:18 INFO - 'XPC_SERVICE_NAME': '0', 06:09:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:09:19 INFO - Downloading/unpacking jsonschema 06:09: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 06:09: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 06:09: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 06:09: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 06:09: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 06:09: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 06:09:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:09:21 INFO - Downloading/unpacking functools32 (from jsonschema) 06:09:21 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 06:09:21 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 06:09:21 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 06:09:21 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 06:09:21 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 06:09:21 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 06:09:21 INFO - Downloading functools32-3.2.3-2.tar.gz 06:09:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:09:22 INFO - warning: no files found matching '*.txt' 06:09:22 INFO - no previously-included directories found matching 'build' 06:09:22 INFO - no previously-included directories found matching 'dist' 06:09:22 INFO - no previously-included directories found matching '.git*' 06:09:22 INFO - Installing collected packages: jsonschema, functools32 06:09:22 INFO - Running setup.py install for functools32 06:09:22 INFO - warning: no files found matching '*.txt' 06:09:22 INFO - no previously-included directories found matching 'build' 06:09:22 INFO - no previously-included directories found matching 'dist' 06:09:22 INFO - no previously-included directories found matching '.git*' 06:09:22 INFO - Successfully installed jsonschema functools32 06:09:22 INFO - Cleaning up... 06:09:22 INFO - Return code: 0 06:09:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:09: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')]} 06:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:09:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09: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')]} 06:09:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:09:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:09: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103a75a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103a7b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fccd8596f40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103919d30>, '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 0x10373ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103910b20>, '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.SM8W7GrC5K/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.vrfyYoThsp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:09: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', 'flake8'] in /builds/slave/test/build 06:09: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 flake8 06:09:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM8W7GrC5K/Render', 06:09:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:09:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:09:22 INFO - 'HOME': '/Users/cltbld', 06:09:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:09:22 INFO - 'LOGNAME': 'cltbld', 06:09:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:09:22 INFO - 'MOZ_NO_REMOTE': '1', 06:09:22 INFO - 'NO_EM_RESTART': '1', 06:09:22 INFO - 'PAGER': '/bin/cat', 06:09:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:09:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:09:22 INFO - 'PWD': '/builds/slave/test', 06:09:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:09:22 INFO - 'SHELL': '/bin/bash', 06:09:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrfyYoThsp/Listeners', 06:09:22 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:09:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:09:22 INFO - 'USER': 'cltbld', 06:09:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:09:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:09:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:09:22 INFO - 'XPC_FLAGS': '0x0', 06:09:22 INFO - 'XPC_SERVICE_NAME': '0', 06:09:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:09:23 INFO - Downloading/unpacking flake8 06:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:09:25 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:09:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:09: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 06:09: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 06:09: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 06:09: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 06:09: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 06:09: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 06:09:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:09:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:09: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 06:09: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 06:09: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 06:09: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 06:09: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 06:09: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 06:09:26 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:09: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 06:09: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 06:09: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 06:09: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 06:09: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 06:09: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 06:09:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:09:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:09:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:09:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:09:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:09:26 INFO - no previously-included directories found matching 'docs/_build' 06:09:26 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:09:26 INFO - Running setup.py install for pep8 06:09:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:09:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:09:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:09:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:09:27 INFO - no previously-included directories found matching 'docs/_build' 06:09:27 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:09:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:09:27 INFO - Cleaning up... 06:09:27 INFO - Return code: 0 06:09:27 INFO - Running post-action listener: _resource_record_post_action 06:09:27 INFO - Running post-action listener: _start_resource_monitoring 06:09:27 INFO - Starting resource monitoring. 06:09:27 INFO - ##### 06:09:27 INFO - ##### Finished create-virtualenv step (success) 06:09:27 INFO - ##### 06:09:27 INFO - ##### 06:09:27 INFO - ##### Running install step. 06:09:27 INFO - ##### 06:09:27 INFO - Running pre-action listener: _resource_record_pre_action 06:09:27 INFO - Running main action method: install 06:09:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:09:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:09:27 INFO - Reading from file tmpfile_stdout 06:09:27 INFO - Detecting whether we're running mozinstall >=1.0... 06:09:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:09:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:09:27 INFO - Reading from file tmpfile_stdout 06:09:27 INFO - Output received: 06:09:27 INFO - Usage: mozinstall [options] installer 06:09:27 INFO - Options: 06:09:27 INFO - -h, --help show this help message and exit 06:09:27 INFO - -d DEST, --destination=DEST 06:09:27 INFO - Directory to install application into. [default: 06:09:27 INFO - "/builds/slave/test"] 06:09:27 INFO - --app=APP Application being installed. [default: firefox] 06:09:27 INFO - mkdir: /builds/slave/test/build/application 06:09:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:09:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:10:01 INFO - Reading from file tmpfile_stdout 06:10:01 INFO - Output received: 06:10:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:10:01 INFO - Running post-action listener: _resource_record_post_action 06:10:01 INFO - ##### 06:10:01 INFO - ##### Finished install step (success) 06:10:01 INFO - ##### 06:10:01 INFO - ##### 06:10:01 INFO - ##### Running run-tests step. 06:10:01 INFO - ##### 06:10:01 INFO - Running pre-action listener: _resource_record_pre_action 06:10:01 INFO - Running main action method: run_tests 06:10:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:10:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:10:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:10:01 INFO - 2016-06-02 06:10:01.476 screenresolution[7043:116730] starting screenresolution argv=screenresolution get 06:10:01 INFO - 2016-06-02 06:10:01.478 screenresolution[7043:116730] Display 0: 1600x1200x32@60 06:10:01 INFO - 2016-06-02 06:10:01.484 screenresolution[7044:116732] starting screenresolution argv=screenresolution list 06:10:01 INFO - Available Modes on Display 0 06:10:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:10:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:10:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:10:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:10:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:10:01 INFO - Intel Iris: 06:10:01 INFO - Chipset Model: Intel Iris 06:10:01 INFO - Type: GPU 06:10:01 INFO - Bus: Built-In 06:10:01 INFO - VRAM (Dynamic, Max): 1536 MB 06:10:01 INFO - Vendor: Intel (0x8086) 06:10:01 INFO - Device ID: 0x0a2e 06:10:01 INFO - Revision ID: 0x0009 06:10:01 INFO - Displays: 06:10:01 INFO - Display: 06:10:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:10:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:10:01 INFO - Display Serial Number: 1600x1200 60 06:10:01 INFO - Main Display: Yes 06:10:01 INFO - Mirror: Off 06:10:01 INFO - Online: Yes 06:10:01 INFO - Rotation: Supported 06:10:01 INFO - Return code: 0 06:10:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:10:01 INFO - Python 2.7.3 06:10:01 INFO - Return code: 0 06:10:01 INFO - grabbing minidump binary from tooltool 06:10:01 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')]} 06:10:01 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 0x103919d30>, '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 0x10373ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103910b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:10:01 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 06:10:01 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 06:10:01 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 06:10:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:10:01 INFO - Return code: 0 06:10:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:10:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:10:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:10:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:10:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:10:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:10:02 INFO - Return code: 0 06:10:02 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/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0235', '--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 06:10:02 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/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0235 --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 06:10:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SM8W7GrC5K/Render', 06:10:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:10:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:10:02 INFO - 'HOME': '/Users/cltbld', 06:10:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:10:02 INFO - 'LOGNAME': 'cltbld', 06:10:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:10:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:10:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:10:02 INFO - 'MOZ_NO_REMOTE': '1', 06:10:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:10:02 INFO - 'NO_EM_RESTART': '1', 06:10:02 INFO - 'PAGER': '/bin/cat', 06:10:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:10:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:10:02 INFO - 'PWD': '/builds/slave/test', 06:10:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:10:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:10:02 INFO - 'SHELL': '/bin/bash', 06:10:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vrfyYoThsp/Listeners', 06:10:02 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:10:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:10:02 INFO - 'USER': 'cltbld', 06:10:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:10:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:10:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:10:02 INFO - 'XPC_FLAGS': '0x0', 06:10:02 INFO - 'XPC_SERVICE_NAME': '0', 06:10:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:10:02 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/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0235', '--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 06:10:03 INFO - mozversion application_buildid: 20160602041745 06:10:03 INFO - mozversion application_changeset: e74192b1a9c82ebcb431e5b4c28775a8111c8864 06:10:03 INFO - mozversion application_display_name: Nightly 06:10:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:10:03 INFO - mozversion application_name: Firefox 06:10:03 INFO - mozversion application_remotingname: firefox 06:10:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 06:10:03 INFO - mozversion application_vendor: Mozilla 06:10:03 INFO - mozversion application_version: 49.0a1 06:10:03 INFO - mozversion platform_buildid: 20160602041745 06:10:03 INFO - mozversion platform_changeset: e74192b1a9c82ebcb431e5b4c28775a8111c8864 06:10:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 06:10:03 INFO - mozversion platform_version: 49.0a1 06:10:03 INFO - using testdate: 1464873003 06:10:03 INFO - actual date: 1464873003 06:10:03 INFO - starting webserver on 'localhost:63574' 06:10:03 INFO - SUITE-START | Running 2 tests 06:10:03 INFO - TEST-START | dromaeo_css 06:10:03 INFO - operating with platform_type : mac_ 06:10:03 INFO - Initialising browser for dromaeo_css test... 06:10:03 INFO - TEST-INFO | started process 7062 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmpP4n0au/profile http://localhost:63574/getInfo.html) 06:10:05 INFO - PROCESS | 7062 | __metrics Screen width/height:1600/1200 06:10:05 INFO - PROCESS | 7062 | colorDepth:24 06:10:05 INFO - PROCESS | 7062 | Browser inner width/height: 1024/689 06:10:05 INFO - PROCESS | 7062 | __metrics 06:10:05 INFO - PROCESS | 7062 | 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" 06:10:05 ERROR - PROCESS | 7062 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:10:06 INFO - TEST-INFO | 7062: exit 0 06:10:06 INFO - Browser initialized. 06:10:06 INFO - Running cycle 1/1 for dromaeo_css test... 06:10:06 INFO - TEST-INFO | started process 7065 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmpP4n0au/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:10:18 INFO - PROCESS | 7065 | RSS: Main: 242196480 06:10:18 INFO - PROCESS | 7065 | 06:11:09 INFO - PROCESS | 7065 | 1464873069529 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] 06:13:19 INFO - PROCESS | 7065 | Cycle 1(1): loaded http://localhost:63574/tests/dromaeo/cssquery-dojo.html (next: http://localhost:63574/tests/dromaeo/cssquery-ext.html) 06:13:21 INFO - PROCESS | 7065 | RSS: Main: 327622656 06:13:21 INFO - PROCESS | 7065 | 06:16:24 INFO - PROCESS | 7065 | Cycle 1(1): loaded http://localhost:63574/tests/dromaeo/cssquery-ext.html (next: http://localhost:63574/tests/dromaeo/cssquery-jquery.html) 06:16:24 INFO - PROCESS | 7065 | RSS: Main: 373366784 06:16:24 INFO - PROCESS | 7065 | 06:19:26 INFO - PROCESS | 7065 | Cycle 1(1): loaded http://localhost:63574/tests/dromaeo/cssquery-jquery.html (next: http://localhost:63574/tests/dromaeo/cssquery-mootools.html) 06:19:27 INFO - PROCESS | 7065 | RSS: Main: 375980032 06:19:27 INFO - PROCESS | 7065 | 06:22:29 INFO - PROCESS | 7065 | Cycle 1(1): loaded http://localhost:63574/tests/dromaeo/cssquery-mootools.html (next: http://localhost:63574/tests/dromaeo/cssquery-prototype.html) 06:22:29 INFO - PROCESS | 7065 | RSS: Main: 371851264 06:22:29 INFO - PROCESS | 7065 | 06:25:33 INFO - PROCESS | 7065 | Cycle 1(1): loaded http://localhost:63574/tests/dromaeo/cssquery-prototype.html (next: http://localhost:63574/tests/dromaeo/cssquery-yui.html) 06:25:34 INFO - PROCESS | 7065 | RSS: Main: 338903040 06:25:34 INFO - PROCESS | 7065 | 06:28:36 INFO - PROCESS | 7065 | Cycle 1(1): loaded http://localhost:63574/tests/dromaeo/cssquery-yui.html (next: http://localhost:63574/tests/dromaeo/cssquery-dojo.html) 06:28:37 INFO - PROCESS | 7065 | RSS: Main: 400056320 06:28:37 INFO - PROCESS | 7065 | 06:28:37 INFO - PROCESS | 7065 | __start_tp_report 06:28:37 INFO - PROCESS | 7065 | _x_x_mozilla_page_load 06:28:37 INFO - PROCESS | 7065 | _x_x_mozilla_page_load_details 06:28:37 INFO - PROCESS | 7065 | |i|pagename|runs| 06:28:37 INFO - PROCESS | 7065 | |0;dojo.html;2988.6861879502653;3386.5258863759077;3389.305192435551;3396.2018925552493;3458.7924724516515;5522.806701765439;5555.3611334696525;5612.242347283115;5635.126555383912;5635.690037047966;5576.609637325389;5582.134769110784;5711.7715291388395;5740.081586946079;5765.3369862465825;4283.400323954634;5086.821961231358;5157.638965272433;5160.380754309469;5323.254744335796;5206.635535512516;5216.556592689626;5228.2680424740565;5250.553702935255;5259;5051.242313652945;5085.8728531787;5112.054269960069;5151.098557752416;5152.510511501401;5089.160288552384;5116.820911268088;5127.846164615067;5182.300389447414;5194.532492075694;5066.290719299301;5092.159793634046;5108.080545501808;5193.065248255312;5200.193969934666;5610.4670056344585;5634.6337488063145;5646.604737668365;5649.661020338752;5657.886842263173;5944.4352786485215;6008.969955150196;6081;6098.280951554063;6112.930237208515;6180.6291622502795;6201.565890387675;6254.499640028786;6254.7498100075945;6269.62382257066;5686.317641882959;5705.286839145107;5710.628809127394;5735.283089613757;5767.913481297784;6146.033269353853;6247.843803904848;6306.400891915261;6308.8422789429715;6358.395952384516;6041.310223412485;6054.545909056838;6095.634261944298;6096.786612468542;6106.938930610637;5218.243354713622;6038.245219347582;6214.502839772807;6228.408301211378;6242.438180563771;5991.64050156992;6067.696615169224;6113.541484388688;6118.388161183846;6160.9383906161265;6108.980957922437;6129.273025824715;6169.598976066542;6170.969145154246;6192.783252586138;4744.169770290186;4861.51384861511;4871.95128048715;4884.8290309838985;4902.3872015998;4337.219300525936;4392.494863090786;4439.977800110979;4443.266860967969;4447.933281000787;5559.861003474945;5602.719864006784;5619.775208991677;5660.377358490563;5663.915041274384;6191.226096737908;6263.655498947602;6271.529635277372;6286.968565157236;6289.056641503811;5597.74810133545;5650.830475085754;5662.716864156776;5663.943360566342;5725;5206.973965130226;5258.579313654898;5266.289050978069;5313.946860531422;5328.520433161034;6328.557001009885;6346.30190679025;6349.777757778455;6359.427651511386;6371.52213583983;6123.142760013513;6268.153799237227;6276.968615156895;6282.026285925689;6289.150964619717;6226.159468471698;6278.246610406598;6319.957207060784;6356.014817703263;6361.2048493938255;6105.247473116839;6138.802933427939;6141.004173643494;6156.507479401547;6168.444839964424;6195.380461953769;6214.564980451325;6228.252609686867;6242.001279795211;6243.625382477066;5839.182514448065;5843.29880414353;5895.616784908967;5940.346561878104;5970.104484327386;5888.116782482662;5946.613470124428;5950.553708471795;5958.612690175125;5969.791057312973;5784.248047753765;5826.76692932278;5829.970850145722;5837.912431313619;5891.705414729161;4623.306504024423;5828.300603927556;5903.380145084704;5950.672713000827;5977.432143946318;5812.622179558315;5820.82537523875;5821.76712931478;5822.243108395882;5833.620814647034 06:28:37 INFO - PROCESS | 7065 | |1;ext.html;506.89355235400564;507.24420613286213;510.6731691717299;550.6806052489555;566.7390057122901;24706.382340441498;25996.610050849253;31586.89445869395;31679.524807127913;31722.524162137557;42378.940526486775;43683;43786.78106609463;45374.319385209164;45579.54420455803;9436.103570160842;9651;9664.27517936154;9666.709998700051;9668.854967175497;8556.914430855704;9486.66796662117;9593.808123837554;9623.56693948774;11595;11553.364564948924;11869.703257418547;11873.109516786233;11873.109516786275;11895;11862.940685296562;11948.342841143734;11954.222975506564;11996.340201288926;11997.640070797846;8575.82848343036;10399.79200415995;11418.60034898783;11694;11727.648170554898;39943;40083.7995810022;40413.79793101045;40420.595794042274;40573.79713101445;3509.298140371936;3514.2268700885766;3553.822308884571;3646.3254297954963;3960.4653371794725;30984.535231971313;31634.209144771336;31637.367252655047;31649.36701265962;31702.207444813834;3236.2071292533246;3288.2601414681744;3289.4407950648447;3293.489509126089;3400.659934006604;499174;500285;501779;502847;504215.99999999633;61448.692756536824;61752;61874;61969;62106;64320.356796432374;64640.67679661571;64764.352356476775;64775;65935;13670;16350.672986540203;16715.913465624697;16920.492385228463;16924.40764573234;12456.56402025943;13015.934920325528;13027.544035958696;13066.803997940038;13127.868721312856;14650.633734156518;14962.401503939938;16386.886491566802;16549.25528351227;16756.329746810236;6556.85255080359;7311.597862117635;7398.485719283405;7473.85052298951;7481.625918704043;910.7632015675972;937.0769791755116;964.4984608003799;1017.8574999500075;1096.4791723931228;29772.702272977425;29805.40389192204;30458.54312185319;30544.847275763477;30783;1080.524569189554;1136.9204155709187;1140.4183866228118;1160.442987366069;1213.4782043721284;11966.700832479084;15627.609309767347;15664.451744189126;16049.358025678843;16148.434804782011;659024;659571;660256.6987165033;661701;664190;59019;59379.40620593739;59480.70259648674;59542.404575954555;59542.404575954555;1313.0677219174302;1445.9204743738985;1458.431211827388;1510.5694876960013;1566.8824838137186;24018.39954001164;28130.71869281281;31582.36835263328;31698.049058528282;32434.18914527061;50807.237891430916;51568;51961.96076078533;51962.740186298826;52255.69360766011;620360.7783533003;635091;636197;637470;639245;43498;44433.88915277015;45301.77349113365;45475;45628.771856140505;48410.5158948406;48958;49259.507404926924;49480.75259623679;50001;39586.604133959445;39987.80006099951;40129;40641.39037914493;41261;37654.435183472815;39348;39352;39630;40511.582094626545;38334.424983625824;38648.613513864504;38978.02554936149;39049.60950390573;39121;32739.508907365933;34370.82814585911;34728.78449254264;35101;35431.82284088563;25097.121600743885;25455.745442545336;25501.979920803704;25867;26453.470930580897 06:28:37 INFO - PROCESS | 7065 | |2;jquery.html;390.86710518423735;392.59954846057013;392.99410508842357;397.20360676842927;419.4526143382884;20138.899305503488;20437.591248175027;21023;21619.891900540493;21635.567288654216;20432.489187770276;20541.972901354948;20811.791882081212;21347.67978480324;21359.893200534014;5912.290525136978;6088;6150.50795936324;6155.538334624899;6161.260648722159;5756;5991.850203744898;6023.337432882379;6068.817935461943;6137.969310153443;5894.911576326359;5912.645241285537;5924.970375148118;5929.347771745104;5930.991731405671;5696.259486266779;5966.075258334965;6012.75948962041;6014.51883849291;6022.78920237794;5207.375114986188;5234.476552344773;5253.369595648546;5254.947450525522;5271.894562108775;19264.614707705907;24132.914018869018;24645.38386540351;26387.868060659766;26388.944442222288;2240.3950933247934;2379.8334116611845;2428.7935525480357;2431.3435372449517;2684.7784258162683;9742.415455072718;10162.59349626014;10162.949185254027;10337.586496540129;10373;1860.4232687866804;1907.7996810334992;1923.7210604462414;1967.1049672399167;2022.2113375783456;120349;127286;127367;128276;128529;11344.48949797261;11545.728080610228;11693.941530292293;11711.88288117108;11771.411429428494;14664.853351466349;14787.334569944378;14793.556193314218;14799.778003329959;14839.554813355617;8523.872141917876;8568.100349463217;8583.227509524047;8587.871181932276;8692.478451292942;7007.684654190573;7470.655282049174;8598.355123365773;8695.913040869636;8755.212030917248;3706.8517259309865;4274.166537525215;4473.239549276631;4499.752513611783;4548.795304211319;1577.3690523790576;1924.6134792857038;1929.1222493765304;1933.265359163537;1963.244151001846;856.2251162697769;879.4898958603994;937.7936853892132;1031.654395777408;1068.7755571329951;20058;20824.687629685566;20892.268770592957;20967.58064838725;21033.948302585115;961.1637875048613;972.1493693018608;984.1508831931518;1007.6271779441654;1013.9594416223416;9710.17463515609;10861.945690271497;11322.716932076599;11324;11401.20191586581;587501;589898;596414;598788;607059;102921;128958;133850;136681.3165934184;137661.3116934409;821.7904434369317;959.4962644611635;984.1290457944771;985.4875464758286;993.2203220472039;18516.166772495002;18847.717284240473;18941.242350305834;19011.809881901005;19355.903220483808;48209.75895120502;50457.49542504528;56902.43097569137;57122;57505.71247143738;81026.59486702528;83320;83461.58269208614;83542;85832;7279.927200728137;7889;7980.162082981204;8045.798855028671;8244.711435099744;12325.260484370967;12761.553345632858;12848.614541563767;13137;13248.006399519882;10797.838032429363;11192.328460292409;11364.147688923198;11364.659060228207;11365.374904380338;9448.952755236454;10046.899531004889;10905.781884362426;11183.217174797686;11189.272697274651;10134.695959121238;10755.731106722395;11319.660410187707;11437.942810285895;11485;8111.878321825296;8145;8166.510009399455;8374.790630234049;8392.874106888523;11131.666050018512;11229.494672739584;11238.269512481662;11403.714907127389;11460 06:28:37 INFO - PROCESS | 7065 | |3;mootools.html;1426.7003929174873;1570.8664763495105;1594.1391648509805;1848.750418693476;1986.6721990871506;7758.961205193973;8189.05825830029;8201.05687845898;8217.794555136124;8519.531425771596;8520.616572254248;8569.785755356119;8720.52037137957;8726.694565690186;8753.956230218855;2373.86943718095;2409.156795121702;2412.348665860217;2413.8551686898754;2416.347586151738;2365.73976862545;2403.230966090852;2403.819713521484;2407.2296865003204;2412.6984126984125;2363.6336367862923;2372;2391.7966972807244;2396.1254142238045;2400.147947478646;2342.7540108288727;2366.715994080713;2374.1809075868796;2377;2387.5344307859973;2325.3256555598855;2342.273895092527;2351.036075209156;2357.9174728884577;2366.863905325436;8630.352723545697;8699.217070463625;8704.086070962585;8705.085965973545;8741.169588889044;5142.794288228466;5190.870228244324;5369.48989845964;5384.461553844623;5387.164989426645;3493.1616412061176;3598.748087633867;3687.2441149564543;3688.207035487383;3688.76023058503;991.7946121427761;1075.419273592267;1075.9569617215302;1095.2169199022737;1097.253867370188;10771.5691372345;11451.828222576669;11459.255148415326;11462.541498340057;11481.770364592745;4421.712588681789;4502.864914052584;4564.383808185852;4569.246074397754;4597;9028.09719028092;9041.954790226007;9051.321150913707;9054.773630659287;9058.230050445654;5173.3533308336455;5173.870653233624;5180.637355385163;5181.81863634771;5193.961207758463;3933.5083114610675;4348.173846969065;4511.684182107221;4770.976145119322;4849.199882019428;3214.951775723366;3749.7000239980734;3758.8872333830027;3804.144067584771;3912.139329347539;1157.8537248124464;1168.0888906652824;1175.5121624525898;1180.451090243041;1186.424584076722;661.2263651527724;788.3220430429826;811.8173410982482;957.6121670723368;996;5629.018682046614;5817.680027598438;5868.09044598088;5876.353601103875;5904.704764761745;476.3045952908792;493.6396430605606;494.7402613627874;495.68276303166004;652.7760449157829;3532.6114127445962;3738.43923411485;3773.8679146230297;3994.2810294147334;4040.939385909214;10280.485975701185;10563.996420340209;10696.41169735672;10699.25105242641;10723.83914241287;10595.576176952989;10668.14654827612;10672.25294229412;10673.786524269626;10677.252592318464;7699.11460182075;8069.596520173968;8154.714584989497;8216.507009579444;8223.794405139919;5883.411658834082;5968.641881487125;5973.295502794734;6143.692815359124;6240.188775459343;9403.341766076583;9726.076022777825;9735.561899714388;9909.30634855582;9916.851247231441;9758.170555502862;10093.243006774668;10095.394276343442;10234.539445724811;10240.283180177315;7397.593132377771;7614.505057171266;7658.617069146632;7663.233676632292;7667.539947603162;8181.713640022571;8195.139510351328;8276.917230827614;8281;8288.419810613199;7246.384057355184;7485.139208991036;7487.933555944911;7572.3942084632;7620.085589729268;7248.456365772483;7320;7341.4860959732305;7469.925300746923;7514.098308203051;6402.231732192166;6610.10763546915;6931.480138989697;7025.824354391181;7028.824279392851;4813.566778989908;4949.950500494949;4966.701997880139;4968.27959945813;5012.072766538202;4831.685940413862;4838.250071238964;4951.282064100757;4962.478939711278;5037.244413338029 06:28:37 INFO - PROCESS | 7065 | |4;prototype.html;275.5549787093843;297.26427092944965;317.22755091352553;321.11852963614865;322.90312906128156;4399.934000989983;4445.711028783135;5146.974265128674;5175.301334319865;5192.376914770228;4489.730616163032;4591.24244499658;4984.975075124628;5165.793368265265;5165.896682066365;3616.7649102808364;3758.774473531583;3787.5265591801026;3792.374258247386;3928.607139286077;3057.969420305802;3756.8309426075893;3899.9025024374478;3935.6261155190355;4010;3466.6533346665383;3741.9438708419398;3961.286968345711;3969.6030396960364;3994.300997325471;2817.8591070446396;3758.8120593970193;3760.3231418344685;3836.3478208704446;3901.453796468497;2623.8950441982297;2750.35366688828;2852.7717782577342;3425.537552430415;3441.4149594568735;4519.276915693506;4752.334673145762;4997.575206107485;4999.725015124167;5021.57316628087;4389.626881715058;4489.653104068766;4501.819927202908;4526.7283962962;4544.2047641662575;5350.010248104113;5386.48828361309;5399.082156033483;5405.756611006427;5436.456354364532;3660.963390366089;3793.9620603793883;3817.2174704185577;3834.3098242316373;3839.78881161536;4528.984601852178;5313.335833020872;5660.518855897295;5670.092785154356;5696.3733989261145;2956.2904902823598;3196.577523002288;3506.1234691327168;3733.7199710021855;3742.8128593570214;3311.966880331214;3714.68425183857;3811.7903515306925;3836.9664078038686;3875.457435958968;3904.882853514399;4234.661227101825;4395.780210989437;4438.134563760101;4440.222960981815;4308.612224899774;4468.905118245985;5237.188235823453;5454.200113962383;5469;1159.9014083802972;1208.0939295528353;1315.8223390065837;1317.5059352742721;1320.9960430073213;756.6027835386357;803.6704950970075;810.7433478108908;818.7789296889806;826.2192228344157;4460.9107817844115;4886.853394398174;4908.803647854046;4924.679895806761;4950.985048065138;5271.947280527223;5445.393608885388;5616.9157462638095;5643.181738647873;5646.096624540054;4922.852314430573;5090.643654944192;5106.183010718341;5131.922296317741;5134.127198376285;2611.608258761163;2705.9052933147245;2763.4473105379006;2774.680911695181;2917.679055303915;5729.684867332337;5840.890230856124;5848.502877255481;5871.295444546682;5877.647341159459;4644.558766917137;4911.803527858846;5359.624826262124;5391.4608539145775;5428.37573679032;4698.88252793676;5147;5516.200150978166;5722.513586345207;5948.434898684618;4937.259411088366;5383.434739352311;5440.374356949002;5569.830335629563;5687.8862422752145;4933.531314525115;5290.28581141541;5461.617686761889;5569.888602228014;5585.218069470358;4832.782524786463;5609.579281553982;5838.095095260242;5922.348541660328;5955.791547295824;3805.372113601225;3813.5402665281295;3832.9233415330978;3855.614438556122;3856.267309211241;5299.258103865539;5426.960901894951;5589.524890384363;5609.80365687199;5624.606277560531;4618.330342100444;4835.6131509478455;5471.50756431923;5476.2607048048;5478.260434841245;4890.6087512998165;4939.666290101581;5483.369412517612;5512.889742205213;5515.641483303572;4147.937780933228;4605.93091103627;5207.843764687065;5338.599605029561;5371.677699338053;4869.586085182657;5153.320273457706;5294.120587191492;5369.69103285591;5453.7545810439415;4985.750712464434;5126;5353.527779860569;5358.46415358461;5364.785408583769 06:28:37 INFO - PROCESS | 7065 | |5;yui.html;725.9709611615535;757.0990521279676;786.1666633368628;820.6389188756947;821.9178082191785;15046.322915468803;15631.374745010186;15633.140177290259;15644.139572323518;15656.530304090882;14020.088694234868;14206.36071376788;14246.786298205509;14264.786028209584;14286.214258215794;716.247939663354;752.7252552818237;894.1282249806419;930.483581612205;941.9873635841464;897.241830653097;902.2150728865896;909.5906841921129;914.1498406481969;915.2403505090792;983.0808194338281;986.5560497775994;991.3457118301923;993.6720882108923;995.1491474789024;697.5675081449508;753.5779963220614;928.2991341537164;969.9660511882121;974.8976357482433;715.6886754261913;716.3409663109951;717.8241330873917;718.4180813541049;718.7628082732738;14712.48506302285;14767.335469903772;14781.187034713086;14796.038257513228;14815.18516481593;13466.596002119853;13576.389062492211;13595.796063058962;13668.93165534176;13949.790753138712;12426.875731242662;12526.185797923197;12551.93724031374;12591.496340146383;12701.61895143138;4393.780310984438;4467.419235499397;4528.207563676344;4528.682992190548;4578.2903649934315;95448;100237.49881250547;101841.4723779144;101855.49072254592;102128.89355532193;10761;10884.782304354027;10907.890921090688;11027.283226590407;11447.19869609136;21069.683954740693;21324.253651122137;21332;21354.89322553377;21453.892730536558;6620.834479137964;7869.921300787033;7883.881741773878;7919.722809701633;8052;4318.870433886988;4402.493713222956;4412.3160910059;4429.402030725875;4614.561616646413;3440.2259491614186;3457.3258214647904;3471.114865709228;3479.2171761353998;3485.006773069663;635.2884769058592;637.7990932856136;643.4948565376158;648.8442773734364;654.9738010479623;565.7341049706631;637.7480895046524;651.7360469010058;651.944584710305;654.3685343643407;8483.618237179342;8990.056044115405;9101.453912765255;9216.723498295061;9263.629454821867;503.38838311451764;556.0602581637102;602.6444397805228;645.4416929356073;658.8221180281297;4289.378040184219;4340.197063543254;4350.630196433339;4438.090191510732;4439.933400998988;119682;121512;122285.38857305657;123452.38273808574;142219;112709;115120;115730;119694.40152799181;120156;108321.91678083276;109042;109974.90025099806;111066.33400498999;112893.43553282345;9696.272779541421;9921.206303495559;9952.154066904393;10023.398596084258;10031.29780915358;58679.70660146672;59700.40299596948;60125.39874601198;60710;62173;71571.64214178895;87236.69144962958;88784;92517.47870595714;92943;12928.353582321035;13172.209667419986;13219.933900330821;13326.466941322626;13588.59234222975;14411.567652970427;14520.564383068524;14714.926425367805;14969.850301496846;15287.312070957056;13573.253471059186;13767.036307458382;13886.89836378824;13901.791473127709;14294.999350045397;13429.127106738031;13912.860871391156;14107.647308817363;14160.787588186391;14253.786193207317;13202.801957970447;13508.594742157751;13609.251491168081;13653.522126725517;13668.52160174389;11370.260933039326;11433.314001159957;11437.656870293904;11477.942610286895;11548;9079.954600226956;11062.723431914266;11239.381833999209;11254.437278135896;11488.080953523531 06:28:37 INFO - PROCESS | 7065 | __end_tp_report 06:28:37 INFO - PROCESS | 7065 | __start_cc_report 06:28:37 INFO - PROCESS | 7065 | _x_x_mozilla_cycle_collect,1280 06:28:37 INFO - PROCESS | 7065 | __end_cc_report 06:28:37 INFO - PROCESS | 7065 | __startTimestamp1464874117235__endTimestamp 06:28:37 INFO - PROCESS | 7065 | 06:28:37 INFO - PROCESS | 7065 | ------- Summary: start ------- 06:28:37 INFO - PROCESS | 7065 | Number of tests: 6 06:28:37 INFO - PROCESS | 7065 | 06:28:37 INFO - PROCESS | 7065 | [#0] dojo.html Cycles:165 Average:5665.08 Median:5826.77 stddev:649.75 (11.2%) stddev-sans-first:616.88 06:28:37 INFO - PROCESS | 7065 | Values: 2988.7 3386.5 3389.3 3396.2 3458.8 5522.8 5555.4 5612.2 5635.1 5635.7 5576.6 5582.1 5711.8 5740.1 5765.3 4283.4 5086.8 5157.6 5160.4 5323.3 5206.6 5216.6 5228.3 5250.6 5259.0 5051.2 5085.9 5112.1 5151.1 5152.5 5089.2 5116.8 5127.8 5182.3 5194.5 5066.3 5092.2 5108.1 5193.1 5200.2 5610.5 5634.6 5646.6 5649.7 5657.9 5944.4 6009.0 6081.0 6098.3 6112.9 6180.6 6201.6 6254.5 6254.7 6269.6 5686.3 5705.3 5710.6 5735.3 5767.9 6146.0 6247.8 6306.4 6308.8 6358.4 6041.3 6054.5 6095.6 6096.8 6106.9 5218.2 6038.2 6214.5 6228.4 6242.4 5991.6 6067.7 6113.5 6118.4 6160.9 6109.0 6129.3 6169.6 6171.0 6192.8 4744.2 4861.5 4872.0 4884.8 4902.4 4337.2 4392.5 4440.0 4443.3 4447.9 5559.9 5602.7 5619.8 5660.4 5663.9 6191.2 6263.7 6271.5 6287.0 6289.1 5597.7 5650.8 5662.7 5663.9 5725.0 5207.0 5258.6 5266.3 5313.9 5328.5 6328.6 6346.3 6349.8 6359.4 6371.5 6123.1 6268.2 6277.0 6282.0 6289.2 6226.2 6278.2 6320.0 6356.0 6361.2 6105.2 6138.8 6141.0 6156.5 6168.4 6195.4 6214.6 6228.3 6242.0 6243.6 5839.2 5843.3 5895.6 5940.3 5970.1 5888.1 5946.6 5950.6 5958.6 5969.8 5784.2 5826.8 5830.0 5837.9 5891.7 4623.3 5828.3 5903.4 5950.7 5977.4 5812.6 5820.8 5821.8 5822.2 5833.6 06:28:37 INFO - PROCESS | 7065 | 06:28:37 INFO - PROCESS | 7065 | [#1] ext.html Cycles:180 Average:73443.54 Median:39085.30 stddev:161170.42 (412.4%) stddev-sans-first:161529.51 06:28:37 INFO - PROCESS | 7065 | Values: 506.9 507.2 510.7 550.7 566.7 24706.4 25996.6 31586.9 31679.5 31722.5 42378.9 43683.0 43786.8 45374.3 45579.5 9436.1 9651.0 9664.3 9666.7 9668.9 8556.9 9486.7 9593.8 9623.6 11595.0 11553.4 11869.7 11873.1 11873.1 11895.0 11862.9 11948.3 11954.2 11996.3 11997.6 8575.8 10399.8 11418.6 11694.0 11727.6 39943.0 40083.8 40413.8 40420.6 40573.8 3509.3 3514.2 3553.8 3646.3 3960.5 30984.5 31634.2 31637.4 31649.4 31702.2 3236.2 3288.3 3289.4 3293.5 3400.7 499174.0 500285.0 501779.0 502847.0 504216.0 61448.7 61752.0 61874.0 61969.0 62106.0 64320.4 64640.7 64764.4 64775.0 65935.0 13670.0 16350.7 16715.9 16920.5 16924.4 12456.6 13015.9 13027.5 13066.8 13127.9 14650.6 14962.4 16386.9 16549.3 16756.3 6556.9 7311.6 7398.5 7473.9 7481.6 910.8 937.1 964.5 1017.9 1096.5 29772.7 29805.4 30458.5 30544.8 30783.0 1080.5 1136.9 1140.4 1160.4 1213.5 11966.7 15627.6 15664.5 16049.4 16148.4 659024.0 659571.0 660256.7 661701.0 664190.0 59019.0 59379.4 59480.7 59542.4 59542.4 1313.1 1445.9 1458.4 1510.6 1566.9 24018.4 28130.7 31582.4 31698.0 32434.2 50807.2 51568.0 51962.0 51962.7 52255.7 620360.8 635091.0 636197.0 637470.0 639245.0 43498.0 44433.9 45301.8 45475.0 45628.8 48410.5 48958.0 49259.5 49480.8 50001.0 39586.6 39987.8 40129.0 40641.4 41261.0 37654.4 39348.0 39352.0 39630.0 40511.6 38334.4 38648.6 38978.0 39049.6 39121.0 32739.5 34370.8 34728.8 35101.0 35431.8 25097.1 25455.7 25502.0 25867.0 26453.5 06:28:37 INFO - PROCESS | 7065 | 06:28:37 INFO - PROCESS | 7065 | [#2] jquery.html Cycles:180 Average:35382.65 Median:2430.07 stddev:99791.09 (4106.5%) stddev-sans-first:100036.44 06:28:37 INFO - PROCESS | 7065 | Values: 390.9 392.6 393.0 397.2 419.5 20138.9 20437.6 21023.0 21619.9 21635.6 20432.5 20542.0 20811.8 21347.7 21359.9 5912.3 6088.0 6150.5 6155.5 6161.3 5756.0 5991.9 6023.3 6068.8 6138.0 5894.9 5912.6 5925.0 5929.3 5931.0 5696.3 5966.1 6012.8 6014.5 6022.8 5207.4 5234.5 5253.4 5254.9 5271.9 19264.6 24132.9 24645.4 26387.9 26388.9 2240.4 2379.8 2428.8 2431.3 2684.8 9742.4 10162.6 10162.9 10337.6 10373.0 1860.4 1907.8 1923.7 1967.1 2022.2 120349.0 127286.0 127367.0 128276.0 128529.0 11344.5 11545.7 11693.9 11711.9 11771.4 14664.9 14787.3 14793.6 14799.8 14839.6 8523.9 8568.1 8583.2 8587.9 8692.5 7007.7 7470.7 8598.4 8695.9 8755.2 3706.9 4274.2 4473.2 4499.8 4548.8 1577.4 1924.6 1929.1 1933.3 1963.2 856.2 879.5 937.8 1031.7 1068.8 20058.0 20824.7 20892.3 20967.6 21033.9 961.2 972.1 984.2 1007.6 1014.0 9710.2 10861.9 11322.7 11324.0 11401.2 587501.0 589898.0 596414.0 598788.0 607059.0 102921.0 128958.0 133850.0 136681.3 137661.3 821.8 959.5 984.1 985.5 993.2 18516.2 18847.7 18941.2 19011.8 19355.9 48209.8 50457.5 56902.4 57122.0 57505.7 81026.6 83320.0 83461.6 83542.0 85832.0 7279.9 7889.0 7980.2 8045.8 8244.7 12325.3 12761.6 12848.6 13137.0 13248.0 10797.8 11192.3 11364.1 11364.7 11365.4 9449.0 10046.9 10905.8 11183.2 11189.3 10134.7 10755.7 11319.7 11437.9 11485.0 8111.9 8145.0 8166.5 8374.8 8392.9 11131.7 11229.5 11238.3 11403.7 11460.0 06:28:37 INFO - PROCESS | 7065 | 06:28:37 INFO - PROCESS | 7065 | [#3] mootools.html Cycles:180 Average:5614.05 Median:2723.48 stddev:3145.06 (115.5%) stddev-sans-first:3138.14 06:28:37 INFO - PROCESS | 7065 | Values: 1426.7 1570.9 1594.1 1848.8 1986.7 7759.0 8189.1 8201.1 8217.8 8519.5 8520.6 8569.8 8720.5 8726.7 8754.0 2373.9 2409.2 2412.3 2413.9 2416.3 2365.7 2403.2 2403.8 2407.2 2412.7 2363.6 2372.0 2391.8 2396.1 2400.1 2342.8 2366.7 2374.2 2377.0 2387.5 2325.3 2342.3 2351.0 2357.9 2366.9 8630.4 8699.2 8704.1 8705.1 8741.2 5142.8 5190.9 5369.5 5384.5 5387.2 3493.2 3598.7 3687.2 3688.2 3688.8 991.8 1075.4 1076.0 1095.2 1097.3 10771.6 11451.8 11459.3 11462.5 11481.8 4421.7 4502.9 4564.4 4569.2 4597.0 9028.1 9042.0 9051.3 9054.8 9058.2 5173.4 5173.9 5180.6 5181.8 5194.0 3933.5 4348.2 4511.7 4771.0 4849.2 3215.0 3749.7 3758.9 3804.1 3912.1 1157.9 1168.1 1175.5 1180.5 1186.4 661.2 788.3 811.8 957.6 996.0 5629.0 5817.7 5868.1 5876.4 5904.7 476.3 493.6 494.7 495.7 652.8 3532.6 3738.4 3773.9 3994.3 4040.9 10280.5 10564.0 10696.4 10699.3 10723.8 10595.6 10668.1 10672.3 10673.8 10677.3 7699.1 8069.6 8154.7 8216.5 8223.8 5883.4 5968.6 5973.3 6143.7 6240.2 9403.3 9726.1 9735.6 9909.3 9916.9 9758.2 10093.2 10095.4 10234.5 10240.3 7397.6 7614.5 7658.6 7663.2 7667.5 8181.7 8195.1 8276.9 8281.0 8288.4 7246.4 7485.1 7487.9 7572.4 7620.1 7248.5 7320.0 7341.5 7469.9 7514.1 6402.2 6610.1 6931.5 7025.8 7028.8 4813.6 4950.0 4966.7 4968.3 5012.1 4831.7 4838.3 4951.3 4962.5 5037.2 06:28:37 INFO - PROCESS | 7065 | 06:28:37 INFO - PROCESS | 7065 | [#4] prototype.html Cycles:180 Average:4349.21 Median:4910.30 stddev:1365.12 (27.8%) stddev-sans-first:1334.27 06:28:37 INFO - PROCESS | 7065 | Values: 275.6 297.3 317.2 321.1 322.9 4399.9 4445.7 5147.0 5175.3 5192.4 4489.7 4591.2 4985.0 5165.8 5165.9 3616.8 3758.8 3787.5 3792.4 3928.6 3058.0 3756.8 3899.9 3935.6 4010.0 3466.7 3741.9 3961.3 3969.6 3994.3 2817.9 3758.8 3760.3 3836.3 3901.5 2623.9 2750.4 2852.8 3425.5 3441.4 4519.3 4752.3 4997.6 4999.7 5021.6 4389.6 4489.7 4501.8 4526.7 4544.2 5350.0 5386.5 5399.1 5405.8 5436.5 3661.0 3794.0 3817.2 3834.3 3839.8 4529.0 5313.3 5660.5 5670.1 5696.4 2956.3 3196.6 3506.1 3733.7 3742.8 3312.0 3714.7 3811.8 3837.0 3875.5 3904.9 4234.7 4395.8 4438.1 4440.2 4308.6 4468.9 5237.2 5454.2 5469.0 1159.9 1208.1 1315.8 1317.5 1321.0 756.6 803.7 810.7 818.8 826.2 4460.9 4886.9 4908.8 4924.7 4951.0 5271.9 5445.4 5616.9 5643.2 5646.1 4922.9 5090.6 5106.2 5131.9 5134.1 2611.6 2705.9 2763.4 2774.7 2917.7 5729.7 5840.9 5848.5 5871.3 5877.6 4644.6 4911.8 5359.6 5391.5 5428.4 4698.9 5147.0 5516.2 5722.5 5948.4 4937.3 5383.4 5440.4 5569.8 5687.9 4933.5 5290.3 5461.6 5569.9 5585.2 4832.8 5609.6 5838.1 5922.3 5955.8 3805.4 3813.5 3832.9 3855.6 3856.3 5299.3 5427.0 5589.5 5609.8 5624.6 4618.3 4835.6 5471.5 5476.3 5478.3 4890.6 4939.7 5483.4 5512.9 5515.6 4147.9 4605.9 5207.8 5338.6 5371.7 4869.6 5153.3 5294.1 5369.7 5453.8 4985.8 5126.0 5353.5 5358.5 5364.8 06:28:37 INFO - PROCESS | 7065 | 06:28:37 INFO - PROCESS | 7065 | [#5] yui.html Cycles:180 Average:23473.26 Median:3448.78 stddev:35932.62 (1041.9%) stddev-sans-first:35992.83 06:28:37 INFO - PROCESS | 7065 | Values: 726.0 757.1 786.2 820.6 821.9 15046.3 15631.4 15633.1 15644.1 15656.5 14020.1 14206.4 14246.8 14264.8 14286.2 716.2 752.7 894.1 930.5 942.0 897.2 902.2 909.6 914.1 915.2 983.1 986.6 991.3 993.7 995.1 697.6 753.6 928.3 970.0 974.9 715.7 716.3 717.8 718.4 718.8 14712.5 14767.3 14781.2 14796.0 14815.2 13466.6 13576.4 13595.8 13668.9 13949.8 12426.9 12526.2 12551.9 12591.5 12701.6 4393.8 4467.4 4528.2 4528.7 4578.3 95448.0 100237.5 101841.5 101855.5 102128.9 10761.0 10884.8 10907.9 11027.3 11447.2 21069.7 21324.3 21332.0 21354.9 21453.9 6620.8 7869.9 7883.9 7919.7 8052.0 4318.9 4402.5 4412.3 4429.4 4614.6 3440.2 3457.3 3471.1 3479.2 3485.0 635.3 637.8 643.5 648.8 655.0 565.7 637.7 651.7 651.9 654.4 8483.6 8990.1 9101.5 9216.7 9263.6 503.4 556.1 602.6 645.4 658.8 4289.4 4340.2 4350.6 4438.1 4439.9 119682.0 121512.0 122285.4 123452.4 142219.0 112709.0 115120.0 115730.0 119694.4 120156.0 108321.9 109042.0 109974.9 111066.3 112893.4 9696.3 9921.2 9952.2 10023.4 10031.3 58679.7 59700.4 60125.4 60710.0 62173.0 71571.6 87236.7 88784.0 92517.5 92943.0 12928.4 13172.2 13219.9 13326.5 13588.6 14411.6 14520.6 14714.9 14969.9 15287.3 13573.3 13767.0 13886.9 13901.8 14295.0 13429.1 13912.9 14107.6 14160.8 14253.8 13202.8 13508.6 13609.3 13653.5 13668.5 11370.3 11433.3 11437.7 11477.9 11548.0 9080.0 11062.7 11239.4 11254.4 11488.1 06:28:37 INFO - PROCESS | 7065 | -------- Summary: end -------- 06:28:37 INFO - PROCESS | 7065 | 06:28:37 INFO - TEST-INFO | 7065: exit 0 06:28:37 INFO - TEST-OK | dromaeo_css | took 1114713ms 06:28:37 INFO - TEST-START | kraken 06:28:37 INFO - operating with platform_type : mac_ 06:28:37 INFO - Initialising browser for kraken test... 06:28:37 INFO - TEST-INFO | started process 7178 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmpSGUXko/profile http://localhost:63574/getInfo.html) 06:28:40 INFO - PROCESS | 7178 | __metrics Screen width/height:1600/1200 06:28:40 INFO - PROCESS | 7178 | colorDepth:24 06:28:40 INFO - PROCESS | 7178 | Browser inner width/height: 1024/689 06:28:40 INFO - PROCESS | 7178 | __metrics 06:28:40 INFO - PROCESS | 7178 | 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" 06:28:40 ERROR - PROCESS | 7178 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:28:41 INFO - TEST-INFO | 7178: exit 0 06:28:41 INFO - Browser initialized. 06:28:41 INFO - Running cycle 1/1 for kraken test... 06:28:41 INFO - TEST-INFO | started process 7179 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmpSGUXko/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:28:53 INFO - PROCESS | 7179 | RSS: Main: 242323456 06:28:53 INFO - PROCESS | 7179 | 06:28:55 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:63574/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:63574/tests/kraken/driver.html?testName=audio-beat-detection) 06:28:56 INFO - PROCESS | 7179 | RSS: Main: 292179968 06:28:56 INFO - PROCESS | 7179 | 06:28:59 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:63574/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:63574/tests/kraken/driver.html?testName=audio-dft) 06:29:00 INFO - PROCESS | 7179 | RSS: Main: 302137344 06:29:00 INFO - PROCESS | 7179 | 06:29:03 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:63574/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:63574/tests/kraken/driver.html?testName=audio-fft) 06:29:04 INFO - PROCESS | 7179 | RSS: Main: 386605056 06:29:04 INFO - PROCESS | 7179 | 06:29:06 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:63574/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:63574/tests/kraken/driver.html?testName=audio-oscillator) 06:29:06 INFO - PROCESS | 7179 | RSS: Main: 287830016 06:29:06 INFO - PROCESS | 7179 | 06:29:09 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:63574/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:63574/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:29:09 INFO - PROCESS | 7179 | RSS: Main: 291753984 06:29:09 INFO - PROCESS | 7179 | 06:29:12 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:63574/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:63574/tests/kraken/driver.html?testName=imaging-darkroom) 06:29:13 INFO - PROCESS | 7179 | RSS: Main: 328880128 06:29:13 INFO - PROCESS | 7179 | 06:29:15 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:63574/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:63574/tests/kraken/driver.html?testName=imaging-desaturate) 06:29:16 INFO - PROCESS | 7179 | RSS: Main: 330444800 06:29:16 INFO - PROCESS | 7179 | 06:29:19 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:63574/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:63574/tests/kraken/driver.html?testName=json-parse-financial) 06:29:19 INFO - PROCESS | 7179 | RSS: Main: 327913472 06:29:19 INFO - PROCESS | 7179 | 06:29:21 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:63574/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:63574/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:29:22 INFO - PROCESS | 7179 | RSS: Main: 310263808 06:29:22 INFO - PROCESS | 7179 | 06:29:23 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:63574/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:63574/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:29:24 INFO - PROCESS | 7179 | RSS: Main: 317321216 06:29:24 INFO - PROCESS | 7179 | 06:29:26 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:63574/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:63574/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:29:27 INFO - PROCESS | 7179 | RSS: Main: 321478656 06:29:27 INFO - PROCESS | 7179 | 06:29:30 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:63574/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:63574/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:29:31 INFO - PROCESS | 7179 | RSS: Main: 324382720 06:29:31 INFO - PROCESS | 7179 | 06:29:33 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:63574/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:63574/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:29:34 INFO - PROCESS | 7179 | RSS: Main: 324128768 06:29:34 INFO - PROCESS | 7179 | 06:29:36 INFO - PROCESS | 7179 | Cycle 1(1): loaded http://localhost:63574/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:63574/tests/kraken/driver.html?testName=ai-astar) 06:29:36 INFO - PROCESS | 7179 | RSS: Main: 367718400 06:29:36 INFO - PROCESS | 7179 | 06:29:36 INFO - PROCESS | 7179 | __start_tp_report 06:29:36 INFO - PROCESS | 7179 | _x_x_mozilla_page_load 06:29:36 INFO - PROCESS | 7179 | _x_x_mozilla_page_load_details 06:29:36 INFO - PROCESS | 7179 | |i|pagename|runs| 06:29:36 INFO - PROCESS | 7179 | |0;ai-astar;111;104;112;112;112;112;111;111;111;112 06:29:36 INFO - PROCESS | 7179 | |1;audio-beat-detection;159;119;118;169;126;143;153;134;205;126 06:29:36 INFO - PROCESS | 7179 | |2;audio-dft;183;183;182;182;182;184;184;182;195;259 06:29:36 INFO - PROCESS | 7179 | |3;audio-fft;82;75;57;115;56;57;59;63;97;55 06:29:36 INFO - PROCESS | 7179 | |4;audio-oscillator;85;86;92;70;72;89;128;101;82;104 06:29:36 INFO - PROCESS | 7179 | |5;imaging-gaussian-blur;86;84;87;85;87;83;87;86;88;83 06:29:36 INFO - PROCESS | 7179 | |6;imaging-darkroom;93;92;93;93;91;91;92;92;93;91 06:29:36 INFO - PROCESS | 7179 | |7;imaging-desaturate;85;84;84;85;85;86;84;84;84;84 06:29:36 INFO - PROCESS | 7179 | |8;json-parse-financial;57;57;57;58;57;55;57;58;57;57 06:29:36 INFO - PROCESS | 7179 | |9;json-stringify-tinderbox;67;70;79;76;73;72;70;73;72;73 06:29:36 INFO - PROCESS | 7179 | |10;stanford-crypto-aes;85;87;86;87;83;84;82;87;144;85 06:29:36 INFO - PROCESS | 7179 | |11;stanford-crypto-ccm;140;140;144;148;145;143;138;144;184;148 06:29:36 INFO - PROCESS | 7179 | |12;stanford-crypto-pbkdf2;166;168;164;168;166;167;168;166;164;166 06:29:36 INFO - PROCESS | 7179 | |13;stanford-crypto-sha256-iterative;67;69;67;83;84;84;84;68;65;67 06:29:36 INFO - PROCESS | 7179 | __end_tp_report 06:29:36 INFO - PROCESS | 7179 | __start_cc_report 06:29:36 INFO - PROCESS | 7179 | _x_x_mozilla_cycle_collect,1979 06:29:36 INFO - PROCESS | 7179 | __end_cc_report 06:29:36 INFO - PROCESS | 7179 | __startTimestamp1464874176626__endTimestamp 06:29:36 INFO - PROCESS | 7179 | 06:29:36 INFO - PROCESS | 7179 | ------- Summary: start ------- 06:29:36 INFO - PROCESS | 7179 | Number of tests: 14 06:29:36 INFO - PROCESS | 7179 | 06:29:36 INFO - PROCESS | 7179 | [#0] ai-astar Cycles:10 Average:110.80 Median:112.00 stddev:2.44 (2.2%) stddev-sans-first:2.59 06:29:36 INFO - PROCESS | 7179 | Values: 111.0 104.0 112.0 112.0 112.0 112.0 111.0 111.0 111.0 112.0 06:29:36 INFO - PROCESS | 7179 | 06:29:36 INFO - PROCESS | 7179 | [#1] audio-beat-detection Cycles:10 Average:145.20 Median:148.00 stddev:27.26 (18.4%) stddev-sans-first:28.45 06:29:36 INFO - PROCESS | 7179 | Values: 159.0 119.0 118.0 169.0 126.0 143.0 153.0 134.0 205.0 126.0 06:29:36 INFO - PROCESS | 7179 | 06:29:36 INFO - PROCESS | 7179 | [#2] audio-dft Cycles:10 Average:191.60 Median:183.50 stddev:24.01 (13.1%) stddev-sans-first:25.26 06:29:36 INFO - PROCESS | 7179 | Values: 183.0 183.0 182.0 182.0 182.0 184.0 184.0 182.0 195.0 259.0 06:29:36 INFO - PROCESS | 7179 | 06:29:36 INFO - PROCESS | 7179 | [#3] audio-fft Cycles:10 Average:71.60 Median:61.00 stddev:20.62 (33.8%) stddev-sans-first:21.52 06:29:36 INFO - PROCESS | 7179 | Values: 82.0 75.0 57.0 115.0 56.0 57.0 59.0 63.0 97.0 55.0 06:29:36 INFO - PROCESS | 7179 | 06:29:36 INFO - PROCESS | 7179 | [#4] audio-oscillator Cycles:10 Average:90.90 Median:83.50 stddev:16.95 (20.3%) stddev-sans-first:17.85 06:29:36 INFO - PROCESS | 7179 | Values: 85.0 86.0 92.0 70.0 72.0 89.0 128.0 101.0 82.0 104.0 06:29:36 INFO - PROCESS | 7179 | 06:29:36 INFO - PROCESS | 7179 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:86.50 stddev:1.78 (2.1%) stddev-sans-first:1.88 06:29:36 INFO - PROCESS | 7179 | Values: 86.0 84.0 87.0 85.0 87.0 83.0 87.0 86.0 88.0 83.0 06:29:36 INFO - PROCESS | 7179 | 06:29:36 INFO - PROCESS | 7179 | [#6] imaging-darkroom Cycles:10 Average:92.10 Median:92.50 stddev:0.88 (0.9%) stddev-sans-first:0.87 06:29:36 INFO - PROCESS | 7179 | Values: 93.0 92.0 93.0 93.0 91.0 91.0 92.0 92.0 93.0 91.0 06:29:36 INFO - PROCESS | 7179 | 06:29:36 INFO - PROCESS | 7179 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:84.50 stddev:0.71 (0.8%) stddev-sans-first:0.73 06:29:36 INFO - PROCESS | 7179 | Values: 85.0 84.0 84.0 85.0 85.0 86.0 84.0 84.0 84.0 84.0 06:29:36 INFO - PROCESS | 7179 | 06:29:36 INFO - PROCESS | 7179 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:57.00 stddev:0.82 (1.4%) stddev-sans-first:0.87 06:29:36 INFO - PROCESS | 7179 | Values: 57.0 57.0 57.0 58.0 57.0 55.0 57.0 58.0 57.0 57.0 06:29:36 INFO - PROCESS | 7179 | 06:29:36 INFO - PROCESS | 7179 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:73.00 stddev:3.31 (4.5%) stddev-sans-first:2.85 06:29:36 INFO - PROCESS | 7179 | Values: 67.0 70.0 79.0 76.0 73.0 72.0 70.0 73.0 72.0 73.0 06:29:36 INFO - PROCESS | 7179 | 06:29:36 INFO - PROCESS | 7179 | [#10] stanford-crypto-aes Cycles:10 Average:91.00 Median:85.50 stddev:18.70 (21.9%) stddev-sans-first:19.71 06:29:36 INFO - PROCESS | 7179 | Values: 85.0 87.0 86.0 87.0 83.0 84.0 82.0 87.0 144.0 85.0 06:29:36 INFO - PROCESS | 7179 | 06:29:36 INFO - PROCESS | 7179 | [#11] stanford-crypto-ccm Cycles:10 Average:147.40 Median:144.50 stddev:13.28 (9.2%) stddev-sans-first:13.81 06:29:36 INFO - PROCESS | 7179 | Values: 140.0 140.0 144.0 148.0 145.0 143.0 138.0 144.0 184.0 148.0 06:29:36 INFO - PROCESS | 7179 | 06:29:36 INFO - PROCESS | 7179 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.30 Median:166.50 stddev:1.49 (0.9%) stddev-sans-first:1.58 06:29:36 INFO - PROCESS | 7179 | Values: 166.0 168.0 164.0 168.0 166.0 167.0 168.0 166.0 164.0 166.0 06:29:36 INFO - PROCESS | 7179 | 06:29:36 INFO - PROCESS | 7179 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.80 Median:76.00 stddev:8.63 (11.3%) stddev-sans-first:8.79 06:29:36 INFO - PROCESS | 7179 | Values: 67.0 69.0 67.0 83.0 84.0 84.0 84.0 68.0 65.0 67.0 06:29:36 INFO - PROCESS | 7179 | -------- Summary: end -------- 06:29:36 INFO - PROCESS | 7179 | 06:29:37 INFO - TEST-INFO | 7179: exit 0 06:29:37 INFO - TEST-OK | kraken | took 59569ms 06:29:37 INFO - SUITE-END | took 1174s 06:29:37 INFO - Completed test suite (00:19:34) 06:29:37 INFO - javascript benchmark 06:29:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2988.6861879502653, 3386.5258863759077, 3389.305192435551, 3396.2018925552493, 3458.7924724516515, 5522.806701765439, 5555.3611334696525, 5612.242347283115, 5635.126555383912, 5635.690037047966, 5576.609637325389, 5582.134769110784, 5711.7715291388395, 5740.081586946079, 5765.3369862465825, 4283.400323954634, 5086.821961231358, 5157.638965272433, 5160.380754309469, 5323.254744335796, 5206.635535512516, 5216.556592689626, 5228.2680424740565, 5250.553702935255, 5259.0, 5051.242313652945, 5085.8728531787, 5112.054269960069, 5151.098557752416, 5152.510511501401, 5089.160288552384, 5116.820911268088, 5127.846164615067, 5182.300389447414, 5194.532492075694, 5066.290719299301, 5092.159793634046, 5108.080545501808, 5193.065248255312, 5200.193969934666, 5610.4670056344585, 5634.6337488063145, 5646.604737668365, 5649.661020338752, 5657.886842263173, 5944.4352786485215, 6008.969955150196, 6081.0, 6098.280951554063, 6112.930237208515, 6180.6291622502795, 6201.565890387675, 6254.499640028786, 6254.7498100075945, 6269.62382257066, 5686.317641882959, 5705.286839145107, 5710.628809127394, 5735.283089613757, 5767.913481297784, 6146.033269353853, 6247.843803904848, 6306.400891915261, 6308.8422789429715, 6358.395952384516, 6041.310223412485, 6054.545909056838, 6095.634261944298, 6096.786612468542, 6106.938930610637, 5218.243354713622, 6038.245219347582, 6214.502839772807, 6228.408301211378, 6242.438180563771, 5991.64050156992, 6067.696615169224, 6113.541484388688, 6118.388161183846, 6160.9383906161265, 6108.980957922437, 6129.273025824715, 6169.598976066542, 6170.969145154246, 6192.783252586138, 4744.169770290186, 4861.51384861511, 4871.95128048715, 4884.8290309838985, 4902.3872015998, 4337.219300525936, 4392.494863090786, 4439.977800110979, 4443.266860967969, 4447.933281000787, 5559.861003474945, 5602.719864006784, 5619.775208991677, 5660.377358490563, 5663.915041274384, 6191.226096737908, 6263.655498947602, 6271.529635277372, 6286.968565157236, 6289.056641503811, 5597.74810133545, 5650.830475085754, 5662.716864156776, 5663.943360566342, 5725.0, 5206.973965130226, 5258.579313654898, 5266.289050978069, 5313.946860531422, 5328.520433161034, 6328.557001009885, 6346.30190679025, 6349.777757778455, 6359.427651511386, 6371.52213583983, 6123.142760013513, 6268.153799237227, 6276.968615156895, 6282.026285925689, 6289.150964619717, 6226.159468471698, 6278.246610406598, 6319.957207060784, 6356.014817703263, 6361.2048493938255, 6105.247473116839, 6138.802933427939, 6141.004173643494, 6156.507479401547, 6168.444839964424, 6195.380461953769, 6214.564980451325, 6228.252609686867, 6242.001279795211, 6243.625382477066, 5839.182514448065, 5843.29880414353, 5895.616784908967, 5940.346561878104, 5970.104484327386, 5888.116782482662, 5946.613470124428, 5950.553708471795, 5958.612690175125, 5969.791057312973, 5784.248047753765, 5826.76692932278, 5829.970850145722, 5837.912431313619, 5891.705414729161, 4623.306504024423, 5828.300603927556, 5903.380145084704, 5950.672713000827, 5977.432143946318, 5812.622179558315, 5820.82537523875, 5821.76712931478, 5822.243108395882, 5833.620814647034], "value": 5623.377620966415, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [506.89355235400564, 507.24420613286213, 510.6731691717299, 550.6806052489555, 566.7390057122901, 24706.382340441498, 25996.610050849253, 31586.89445869395, 31679.524807127913, 31722.524162137557, 42378.940526486775, 43683.0, 43786.78106609463, 45374.319385209164, 45579.54420455803, 9436.103570160842, 9651.0, 9664.27517936154, 9666.709998700051, 9668.854967175497, 8556.914430855704, 9486.66796662117, 9593.808123837554, 9623.56693948774, 11595.0, 11553.364564948924, 11869.703257418547, 11873.109516786233, 11873.109516786275, 11895.0, 11862.940685296562, 11948.342841143734, 11954.222975506564, 11996.340201288926, 11997.640070797846, 8575.82848343036, 10399.79200415995, 11418.60034898783, 11694.0, 11727.648170554898, 39943.0, 40083.7995810022, 40413.79793101045, 40420.595794042274, 40573.79713101445, 3509.298140371936, 3514.2268700885766, 3553.822308884571, 3646.3254297954963, 3960.4653371794725, 30984.535231971313, 31634.209144771336, 31637.367252655047, 31649.36701265962, 31702.207444813834, 3236.2071292533246, 3288.2601414681744, 3289.4407950648447, 3293.489509126089, 3400.659934006604, 499174.0, 500285.0, 501779.0, 502847.0, 504215.99999999633, 61448.692756536824, 61752.0, 61874.0, 61969.0, 62106.0, 64320.356796432374, 64640.67679661571, 64764.352356476775, 64775.0, 65935.0, 13670.0, 16350.672986540203, 16715.913465624697, 16920.492385228463, 16924.40764573234, 12456.56402025943, 13015.934920325528, 13027.544035958696, 13066.803997940038, 13127.868721312856, 14650.633734156518, 14962.401503939938, 16386.886491566802, 16549.25528351227, 16756.329746810236, 6556.85255080359, 7311.597862117635, 7398.485719283405, 7473.85052298951, 7481.625918704043, 910.7632015675972, 937.0769791755116, 964.4984608003799, 1017.8574999500075, 1096.4791723931228, 29772.702272977425, 29805.40389192204, 30458.54312185319, 30544.847275763477, 30783.0, 1080.524569189554, 1136.9204155709187, 1140.4183866228118, 1160.442987366069, 1213.4782043721284, 11966.700832479084, 15627.609309767347, 15664.451744189126, 16049.358025678843, 16148.434804782011, 659024.0, 659571.0, 660256.6987165033, 661701.0, 664190.0, 59019.0, 59379.40620593739, 59480.70259648674, 59542.404575954555, 59542.404575954555, 1313.0677219174302, 1445.9204743738985, 1458.431211827388, 1510.5694876960013, 1566.8824838137186, 24018.39954001164, 28130.71869281281, 31582.36835263328, 31698.049058528282, 32434.18914527061, 50807.237891430916, 51568.0, 51961.96076078533, 51962.740186298826, 52255.69360766011, 620360.7783533003, 635091.0, 636197.0, 637470.0, 639245.0, 43498.0, 44433.88915277015, 45301.77349113365, 45475.0, 45628.771856140505, 48410.5158948406, 48958.0, 49259.507404926924, 49480.75259623679, 50001.0, 39586.604133959445, 39987.80006099951, 40129.0, 40641.39037914493, 41261.0, 37654.435183472815, 39348.0, 39352.0, 39630.0, 40511.582094626545, 38334.424983625824, 38648.613513864504, 38978.02554936149, 39049.60950390573, 39121.0, 32739.508907365933, 34370.82814585911, 34728.78449254264, 35101.0, 35431.82284088563, 25097.121600743885, 25455.745442545336, 25501.979920803704, 25867.0, 26453.470930580897], "value": 20723.73743433806, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.86710518423735, 392.59954846057013, 392.99410508842357, 397.20360676842927, 419.4526143382884, 20138.899305503488, 20437.591248175027, 21023.0, 21619.891900540493, 21635.567288654216, 20432.489187770276, 20541.972901354948, 20811.791882081212, 21347.67978480324, 21359.893200534014, 5912.290525136978, 6088.0, 6150.50795936324, 6155.538334624899, 6161.260648722159, 5756.0, 5991.850203744898, 6023.337432882379, 6068.817935461943, 6137.969310153443, 5894.911576326359, 5912.645241285537, 5924.970375148118, 5929.347771745104, 5930.991731405671, 5696.259486266779, 5966.075258334965, 6012.75948962041, 6014.51883849291, 6022.78920237794, 5207.375114986188, 5234.476552344773, 5253.369595648546, 5254.947450525522, 5271.894562108775, 19264.614707705907, 24132.914018869018, 24645.38386540351, 26387.868060659766, 26388.944442222288, 2240.3950933247934, 2379.8334116611845, 2428.7935525480357, 2431.3435372449517, 2684.7784258162683, 9742.415455072718, 10162.59349626014, 10162.949185254027, 10337.586496540129, 10373.0, 1860.4232687866804, 1907.7996810334992, 1923.7210604462414, 1967.1049672399167, 2022.2113375783456, 120349.0, 127286.0, 127367.0, 128276.0, 128529.0, 11344.48949797261, 11545.728080610228, 11693.941530292293, 11711.88288117108, 11771.411429428494, 14664.853351466349, 14787.334569944378, 14793.556193314218, 14799.778003329959, 14839.554813355617, 8523.872141917876, 8568.100349463217, 8583.227509524047, 8587.871181932276, 8692.478451292942, 7007.684654190573, 7470.655282049174, 8598.355123365773, 8695.913040869636, 8755.212030917248, 3706.8517259309865, 4274.166537525215, 4473.239549276631, 4499.752513611783, 4548.795304211319, 1577.3690523790576, 1924.6134792857038, 1929.1222493765304, 1933.265359163537, 1963.244151001846, 856.2251162697769, 879.4898958603994, 937.7936853892132, 1031.654395777408, 1068.7755571329951, 20058.0, 20824.687629685566, 20892.268770592957, 20967.58064838725, 21033.948302585115, 961.1637875048613, 972.1493693018608, 984.1508831931518, 1007.6271779441654, 1013.9594416223416, 9710.17463515609, 10861.945690271497, 11322.716932076599, 11324.0, 11401.20191586581, 587501.0, 589898.0, 596414.0, 598788.0, 607059.0, 102921.0, 128958.0, 133850.0, 136681.3165934184, 137661.3116934409, 821.7904434369317, 959.4962644611635, 984.1290457944771, 985.4875464758286, 993.2203220472039, 18516.166772495002, 18847.717284240473, 18941.242350305834, 19011.809881901005, 19355.903220483808, 48209.75895120502, 50457.49542504528, 56902.43097569137, 57122.0, 57505.71247143738, 81026.59486702528, 83320.0, 83461.58269208614, 83542.0, 85832.0, 7279.927200728137, 7889.0, 7980.162082981204, 8045.798855028671, 8244.711435099744, 12325.260484370967, 12761.553345632858, 12848.614541563767, 13137.0, 13248.006399519882, 10797.838032429363, 11192.328460292409, 11364.147688923198, 11364.659060228207, 11365.374904380338, 9448.952755236454, 10046.899531004889, 10905.781884362426, 11183.217174797686, 11189.272697274651, 10134.695959121238, 10755.731106722395, 11319.660410187707, 11437.942810285895, 11485.0, 8111.878321825296, 8145.0, 8166.510009399455, 8374.790630234049, 8392.874106888523, 11131.666050018512, 11229.494672739584, 11238.269512481662, 11403.714907127389, 11460.0], "value": 9642.968662917187, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1426.7003929174873, 1570.8664763495105, 1594.1391648509805, 1848.750418693476, 1986.6721990871506, 7758.961205193973, 8189.05825830029, 8201.05687845898, 8217.794555136124, 8519.531425771596, 8520.616572254248, 8569.785755356119, 8720.52037137957, 8726.694565690186, 8753.956230218855, 2373.86943718095, 2409.156795121702, 2412.348665860217, 2413.8551686898754, 2416.347586151738, 2365.73976862545, 2403.230966090852, 2403.819713521484, 2407.2296865003204, 2412.6984126984125, 2363.6336367862923, 2372.0, 2391.7966972807244, 2396.1254142238045, 2400.147947478646, 2342.7540108288727, 2366.715994080713, 2374.1809075868796, 2377.0, 2387.5344307859973, 2325.3256555598855, 2342.273895092527, 2351.036075209156, 2357.9174728884577, 2366.863905325436, 8630.352723545697, 8699.217070463625, 8704.086070962585, 8705.085965973545, 8741.169588889044, 5142.794288228466, 5190.870228244324, 5369.48989845964, 5384.461553844623, 5387.164989426645, 3493.1616412061176, 3598.748087633867, 3687.2441149564543, 3688.207035487383, 3688.76023058503, 991.7946121427761, 1075.419273592267, 1075.9569617215302, 1095.2169199022737, 1097.253867370188, 10771.5691372345, 11451.828222576669, 11459.255148415326, 11462.541498340057, 11481.770364592745, 4421.712588681789, 4502.864914052584, 4564.383808185852, 4569.246074397754, 4597.0, 9028.09719028092, 9041.954790226007, 9051.321150913707, 9054.773630659287, 9058.230050445654, 5173.3533308336455, 5173.870653233624, 5180.637355385163, 5181.81863634771, 5193.961207758463, 3933.5083114610675, 4348.173846969065, 4511.684182107221, 4770.976145119322, 4849.199882019428, 3214.951775723366, 3749.7000239980734, 3758.8872333830027, 3804.144067584771, 3912.139329347539, 1157.8537248124464, 1168.0888906652824, 1175.5121624525898, 1180.451090243041, 1186.424584076722, 661.2263651527724, 788.3220430429826, 811.8173410982482, 957.6121670723368, 996.0, 5629.018682046614, 5817.680027598438, 5868.09044598088, 5876.353601103875, 5904.704764761745, 476.3045952908792, 493.6396430605606, 494.7402613627874, 495.68276303166004, 652.7760449157829, 3532.6114127445962, 3738.43923411485, 3773.8679146230297, 3994.2810294147334, 4040.939385909214, 10280.485975701185, 10563.996420340209, 10696.41169735672, 10699.25105242641, 10723.83914241287, 10595.576176952989, 10668.14654827612, 10672.25294229412, 10673.786524269626, 10677.252592318464, 7699.11460182075, 8069.596520173968, 8154.714584989497, 8216.507009579444, 8223.794405139919, 5883.411658834082, 5968.641881487125, 5973.295502794734, 6143.692815359124, 6240.188775459343, 9403.341766076583, 9726.076022777825, 9735.561899714388, 9909.30634855582, 9916.851247231441, 9758.170555502862, 10093.243006774668, 10095.394276343442, 10234.539445724811, 10240.283180177315, 7397.593132377771, 7614.505057171266, 7658.617069146632, 7663.233676632292, 7667.539947603162, 8181.713640022571, 8195.139510351328, 8276.917230827614, 8281.0, 8288.419810613199, 7246.384057355184, 7485.139208991036, 7487.933555944911, 7572.3942084632, 7620.085589729268, 7248.456365772483, 7320.0, 7341.4860959732305, 7469.925300746923, 7514.098308203051, 6402.231732192166, 6610.10763546915, 6931.480138989697, 7025.824354391181, 7028.824279392851, 4813.566778989908, 4949.950500494949, 4966.701997880139, 4968.27959945813, 5012.072766538202, 4831.685940413862, 4838.250071238964, 4951.282064100757, 4962.478939711278, 5037.244413338029], "value": 4456.576819122706, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [275.5549787093843, 297.26427092944965, 317.22755091352553, 321.11852963614865, 322.90312906128156, 4399.934000989983, 4445.711028783135, 5146.974265128674, 5175.301334319865, 5192.376914770228, 4489.730616163032, 4591.24244499658, 4984.975075124628, 5165.793368265265, 5165.896682066365, 3616.7649102808364, 3758.774473531583, 3787.5265591801026, 3792.374258247386, 3928.607139286077, 3057.969420305802, 3756.8309426075893, 3899.9025024374478, 3935.6261155190355, 4010.0, 3466.6533346665383, 3741.9438708419398, 3961.286968345711, 3969.6030396960364, 3994.300997325471, 2817.8591070446396, 3758.8120593970193, 3760.3231418344685, 3836.3478208704446, 3901.453796468497, 2623.8950441982297, 2750.35366688828, 2852.7717782577342, 3425.537552430415, 3441.4149594568735, 4519.276915693506, 4752.334673145762, 4997.575206107485, 4999.725015124167, 5021.57316628087, 4389.626881715058, 4489.653104068766, 4501.819927202908, 4526.7283962962, 4544.2047641662575, 5350.010248104113, 5386.48828361309, 5399.082156033483, 5405.756611006427, 5436.456354364532, 3660.963390366089, 3793.9620603793883, 3817.2174704185577, 3834.3098242316373, 3839.78881161536, 4528.984601852178, 5313.335833020872, 5660.518855897295, 5670.092785154356, 5696.3733989261145, 2956.2904902823598, 3196.577523002288, 3506.1234691327168, 3733.7199710021855, 3742.8128593570214, 3311.966880331214, 3714.68425183857, 3811.7903515306925, 3836.9664078038686, 3875.457435958968, 3904.882853514399, 4234.661227101825, 4395.780210989437, 4438.134563760101, 4440.222960981815, 4308.612224899774, 4468.905118245985, 5237.188235823453, 5454.200113962383, 5469.0, 1159.9014083802972, 1208.0939295528353, 1315.8223390065837, 1317.5059352742721, 1320.9960430073213, 756.6027835386357, 803.6704950970075, 810.7433478108908, 818.7789296889806, 826.2192228344157, 4460.9107817844115, 4886.853394398174, 4908.803647854046, 4924.679895806761, 4950.985048065138, 5271.947280527223, 5445.393608885388, 5616.9157462638095, 5643.181738647873, 5646.096624540054, 4922.852314430573, 5090.643654944192, 5106.183010718341, 5131.922296317741, 5134.127198376285, 2611.608258761163, 2705.9052933147245, 2763.4473105379006, 2774.680911695181, 2917.679055303915, 5729.684867332337, 5840.890230856124, 5848.502877255481, 5871.295444546682, 5877.647341159459, 4644.558766917137, 4911.803527858846, 5359.624826262124, 5391.4608539145775, 5428.37573679032, 4698.88252793676, 5147.0, 5516.200150978166, 5722.513586345207, 5948.434898684618, 4937.259411088366, 5383.434739352311, 5440.374356949002, 5569.830335629563, 5687.8862422752145, 4933.531314525115, 5290.28581141541, 5461.617686761889, 5569.888602228014, 5585.218069470358, 4832.782524786463, 5609.579281553982, 5838.095095260242, 5922.348541660328, 5955.791547295824, 3805.372113601225, 3813.5402665281295, 3832.9233415330978, 3855.614438556122, 3856.267309211241, 5299.258103865539, 5426.960901894951, 5589.524890384363, 5609.80365687199, 5624.606277560531, 4618.330342100444, 4835.6131509478455, 5471.50756431923, 5476.2607048048, 5478.260434841245, 4890.6087512998165, 4939.666290101581, 5483.369412517612, 5512.889742205213, 5515.641483303572, 4147.937780933228, 4605.93091103627, 5207.843764687065, 5338.599605029561, 5371.677699338053, 4869.586085182657, 5153.320273457706, 5294.120587191492, 5369.69103285591, 5453.7545810439415, 4985.750712464434, 5126.0, 5353.527779860569, 5358.46415358461, 5364.785408583769], "value": 3915.629629823894, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.9709611615535, 757.0990521279676, 786.1666633368628, 820.6389188756947, 821.9178082191785, 15046.322915468803, 15631.374745010186, 15633.140177290259, 15644.139572323518, 15656.530304090882, 14020.088694234868, 14206.36071376788, 14246.786298205509, 14264.786028209584, 14286.214258215794, 716.247939663354, 752.7252552818237, 894.1282249806419, 930.483581612205, 941.9873635841464, 897.241830653097, 902.2150728865896, 909.5906841921129, 914.1498406481969, 915.2403505090792, 983.0808194338281, 986.5560497775994, 991.3457118301923, 993.6720882108923, 995.1491474789024, 697.5675081449508, 753.5779963220614, 928.2991341537164, 969.9660511882121, 974.8976357482433, 715.6886754261913, 716.3409663109951, 717.8241330873917, 718.4180813541049, 718.7628082732738, 14712.48506302285, 14767.335469903772, 14781.187034713086, 14796.038257513228, 14815.18516481593, 13466.596002119853, 13576.389062492211, 13595.796063058962, 13668.93165534176, 13949.790753138712, 12426.875731242662, 12526.185797923197, 12551.93724031374, 12591.496340146383, 12701.61895143138, 4393.780310984438, 4467.419235499397, 4528.207563676344, 4528.682992190548, 4578.2903649934315, 95448.0, 100237.49881250547, 101841.4723779144, 101855.49072254592, 102128.89355532193, 10761.0, 10884.782304354027, 10907.890921090688, 11027.283226590407, 11447.19869609136, 21069.683954740693, 21324.253651122137, 21332.0, 21354.89322553377, 21453.892730536558, 6620.834479137964, 7869.921300787033, 7883.881741773878, 7919.722809701633, 8052.0, 4318.870433886988, 4402.493713222956, 4412.3160910059, 4429.402030725875, 4614.561616646413, 3440.2259491614186, 3457.3258214647904, 3471.114865709228, 3479.2171761353998, 3485.006773069663, 635.2884769058592, 637.7990932856136, 643.4948565376158, 648.8442773734364, 654.9738010479623, 565.7341049706631, 637.7480895046524, 651.7360469010058, 651.944584710305, 654.3685343643407, 8483.618237179342, 8990.056044115405, 9101.453912765255, 9216.723498295061, 9263.629454821867, 503.38838311451764, 556.0602581637102, 602.6444397805228, 645.4416929356073, 658.8221180281297, 4289.378040184219, 4340.197063543254, 4350.630196433339, 4438.090191510732, 4439.933400998988, 119682.0, 121512.0, 122285.38857305657, 123452.38273808574, 142219.0, 112709.0, 115120.0, 115730.0, 119694.40152799181, 120156.0, 108321.91678083276, 109042.0, 109974.90025099806, 111066.33400498999, 112893.43553282345, 9696.272779541421, 9921.206303495559, 9952.154066904393, 10023.398596084258, 10031.29780915358, 58679.70660146672, 59700.40299596948, 60125.39874601198, 60710.0, 62173.0, 71571.64214178895, 87236.69144962958, 88784.0, 92517.47870595714, 92943.0, 12928.353582321035, 13172.209667419986, 13219.933900330821, 13326.466941322626, 13588.59234222975, 14411.567652970427, 14520.564383068524, 14714.926425367805, 14969.850301496846, 15287.312070957056, 13573.253471059186, 13767.036307458382, 13886.89836378824, 13901.791473127709, 14294.999350045397, 13429.127106738031, 13912.860871391156, 14107.647308817363, 14160.787588186391, 14253.786193207317, 13202.801957970447, 13508.594742157751, 13609.251491168081, 13653.522126725517, 13668.52160174389, 11370.260933039326, 11433.314001159957, 11437.656870293904, 11477.942610286895, 11548.0, 9079.954600226956, 11062.723431914266, 11239.381833999209, 11254.437278135896, 11488.080953523531], "value": 7803.063253127935, "unit": "score"}], "name": "dromaeo_css", "value": 7313.62226401487, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 104.0, 112.0, 112.0, 112.0, 112.0, 111.0, 111.0, 111.0, 112.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 119.0, 118.0, 169.0, 126.0, 143.0, 153.0, 134.0, 205.0, 126.0], "value": 145.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 183.0, 182.0, 182.0, 182.0, 184.0, 184.0, 182.0, 195.0, 259.0], "value": 191.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 75.0, 57.0, 115.0, 56.0, 57.0, 59.0, 63.0, 97.0, 55.0], "value": 71.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 92.0, 70.0, 72.0, 89.0, 128.0, 101.0, 82.0, 104.0], "value": 90.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 85.0, 87.0, 83.0, 87.0, 86.0, 88.0, 83.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 93.0, 91.0, 91.0, 92.0, 92.0, 93.0, 91.0], "value": 92.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 85.0, 85.0, 86.0, 84.0, 84.0, 84.0, 84.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 57.0, 58.0, 57.0, 55.0, 57.0, 58.0, 57.0, 57.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 79.0, 76.0, 73.0, 72.0, 70.0, 73.0, 72.0, 73.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 86.0, 87.0, 83.0, 84.0, 82.0, 87.0, 144.0, 85.0], "value": 91.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 144.0, 148.0, 145.0, 143.0, 138.0, 144.0, 184.0, 148.0], "value": 147.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 168.0, 164.0, 168.0, 166.0, 167.0, 168.0, 166.0, 164.0, 166.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 67.0, 83.0, 84.0, 84.0, 84.0, 68.0, 65.0, 67.0], "value": 73.8, "unit": "score"}], "name": "kraken", "value": 1480.3000000000002, "alertThreshold": 2.0}]} 06:29:37 INFO - Return code: 0 06:29:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:29:37 ERROR - # TBPL SUCCESS # 06:29:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:29:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:29:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:29:37 INFO - 2016-06-02 06:29:37.918 screenresolution[7189:118763] starting screenresolution argv=screenresolution get 06:29:37 INFO - 2016-06-02 06:29:37.921 screenresolution[7189:118763] Display 0: 1600x1200x32@60 06:29:37 INFO - 2016-06-02 06:29:37.927 screenresolution[7190:118766] starting screenresolution argv=screenresolution list 06:29:37 INFO - Available Modes on Display 0 06:29:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:29:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:29:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:29:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:29:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:29:38 INFO - Intel Iris: 06:29:38 INFO - Chipset Model: Intel Iris 06:29:38 INFO - Type: GPU 06:29:38 INFO - Bus: Built-In 06:29:38 INFO - VRAM (Dynamic, Max): 1536 MB 06:29:38 INFO - Vendor: Intel (0x8086) 06:29:38 INFO - Device ID: 0x0a2e 06:29:38 INFO - Revision ID: 0x0009 06:29:38 INFO - Displays: 06:29:38 INFO - Display: 06:29:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:29:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:29:38 INFO - Display Serial Number: 1600x1200 60 06:29:38 INFO - Main Display: Yes 06:29:38 INFO - Mirror: Off 06:29:38 INFO - Online: Yes 06:29:38 INFO - Rotation: Supported 06:29:38 INFO - Return code: 0 06:29:38 INFO - Running post-action listener: _resource_record_post_action 06:29:38 INFO - ##### 06:29:38 INFO - ##### Finished run-tests step (success) 06:29:38 INFO - ##### 06:29:38 INFO - Running post-run listener: _resource_record_post_run 06:29:38 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 880640; Write bytes: 700197376; Read time: 5; Write time: 1900 06:29:38 INFO - TinderboxPrint: CPU usage
53.6% 06:29:38 INFO - TinderboxPrint: I/O read bytes / time
880,640 / 5 06:29:38 INFO - TinderboxPrint: I/O write bytes / time
700,197,376 / 1,900 06:29:38 INFO - TinderboxPrint: CPU idle
2,245.6 (46.4%) 06:29:38 INFO - TinderboxPrint: CPU system
967.0 (20.0%) 06:29:38 INFO - TinderboxPrint: CPU user
1,628.8 (33.6%) 06:29:38 INFO - TinderboxPrint: Swap in / out
400,248,832 / 0 06:29:38 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 395489792; Write bytes: 390928384; Read time: 29900; Write time: 806 06:29:38 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 880640; Write bytes: 295895552; Read time: 5; Write time: 1057 06:29:38 INFO - Running post-run listener: _upload_blobber_files 06:29:38 INFO - Blob upload gear active. 06:29:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:29:38 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 06:29:38 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'] 06:29:38 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 06:29:39 INFO - (blobuploader) - INFO - Open directory for files ... 06:29:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:29:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:29:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:29:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:29:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:29:39 INFO - (blobuploader) - INFO - Done attempting. 06:29:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:29:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:29:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:29:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:29:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:29:40 INFO - (blobuploader) - INFO - Done attempting. 06:29:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:29:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:29:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:29:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:29:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:29:43 INFO - (blobuploader) - INFO - Done attempting. 06:29:43 INFO - (blobuploader) - INFO - Iteration through files over. 06:29:43 INFO - Return code: 0 06:29:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:29:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:29:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ba888d2ee4f70d51233b1d0d01c51a0f0e0f8a342c745ec3232dfb09163d690e238639c967c6379536894b94c04a17a68aae22a7c6246ded26570a5209274d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d19807a1b4d7af6058b064f52a9a3c3b9cee3991f474578092929b37bae45bab5d4d2fd22df66a9c15e394840310325db0959498f5ea240886ba72456814fbcc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/33bd77eb6457bb0fad134fda5d4d4f9383810697fe54a6fc5daf838acb39573970aeecf80986f08ba473fbd4d104683841e6ae45eb3d4e17747f9f0088ca56c4"} 06:29:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:29:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:29:43 INFO - Contents: 06:29:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ba888d2ee4f70d51233b1d0d01c51a0f0e0f8a342c745ec3232dfb09163d690e238639c967c6379536894b94c04a17a68aae22a7c6246ded26570a5209274d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d19807a1b4d7af6058b064f52a9a3c3b9cee3991f474578092929b37bae45bab5d4d2fd22df66a9c15e394840310325db0959498f5ea240886ba72456814fbcc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/33bd77eb6457bb0fad134fda5d4d4f9383810697fe54a6fc5daf838acb39573970aeecf80986f08ba473fbd4d104683841e6ae45eb3d4e17747f9f0088ca56c4"} 06:29:43 INFO - Running post-run listener: copy_logs_to_upload_dir 06:29:43 INFO - Copying logs to upload dir... 06:29:43 INFO - mkdir: /builds/slave/test/build/upload/logs 06:29:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.706513 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-06-02 06:29:43.210166) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 06:29:43.214090) ========= 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.SM8W7GrC5K/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.vrfyYoThsp/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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/8ba888d2ee4f70d51233b1d0d01c51a0f0e0f8a342c745ec3232dfb09163d690e238639c967c6379536894b94c04a17a68aae22a7c6246ded26570a5209274d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d19807a1b4d7af6058b064f52a9a3c3b9cee3991f474578092929b37bae45bab5d4d2fd22df66a9c15e394840310325db0959498f5ea240886ba72456814fbcc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/33bd77eb6457bb0fad134fda5d4d4f9383810697fe54a6fc5daf838acb39573970aeecf80986f08ba473fbd4d104683841e6ae45eb3d4e17747f9f0088ca56c4"} build_url:https://queue.taskcluster.net/v1/task/fvsdsXvkQ1KakihQreZc7Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011569 build_url: 'https://queue.taskcluster.net/v1/task/fvsdsXvkQ1KakihQreZc7Q/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/8ba888d2ee4f70d51233b1d0d01c51a0f0e0f8a342c745ec3232dfb09163d690e238639c967c6379536894b94c04a17a68aae22a7c6246ded26570a5209274d4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d19807a1b4d7af6058b064f52a9a3c3b9cee3991f474578092929b37bae45bab5d4d2fd22df66a9c15e394840310325db0959498f5ea240886ba72456814fbcc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/33bd77eb6457bb0fad134fda5d4d4f9383810697fe54a6fc5daf838acb39573970aeecf80986f08ba473fbd4d104683841e6ae45eb3d4e17747f9f0088ca56c4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 06:29:43.245533) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 06:29:43.245900) ========= 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.SM8W7GrC5K/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.vrfyYoThsp/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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.005282 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 06:29:43.296111) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 06:29:43.296447) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 06:29:43.296887) ========= ========= Total master_lag: 0.19 =========