builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0131 starttime: 1463672180.4 results: success (0) buildid: 20160519074355 builduid: 322091999a014eb6bed730c7e879dee1 revision: e8112fba80d64c7c6615c9019178e68f60b5d718 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 08:36:20.397671) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 08:36:20.398112) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 08:36:20.398416) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 08:36:20.531110) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 08:36:20.531422) ========= 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.IMuiCwMj5B/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.vcVnIgXPF1/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-19 08:36:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 168M=0s 2016-05-19 08:36:20 (168 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.127744 ========= master_lag: 1.78 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 08:36:22.443886) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 08:36:22.444215) ========= 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.IMuiCwMj5B/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.vcVnIgXPF1/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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.026579 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 08:36:22.490665) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 08:36:22.491024) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev e8112fba80d64c7c6615c9019178e68f60b5d718 --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 e8112fba80d64c7c6615c9019178e68f60b5d718 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IMuiCwMj5B/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.vcVnIgXPF1/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-19 08:36:22,567 truncating revision to first 12 chars 2016-05-19 08:36:22,567 Setting DEBUG logging. 2016-05-19 08:36:22,567 attempt 1/10 2016-05-19 08:36:22,567 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e8112fba80d6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 08:36:23,795 unpacking tar archive at: fx-team-e8112fba80d6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.472585 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 08:36:23.985873) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 08:36:23.986281) ========= script_repo_revision: e8112fba80d64c7c6615c9019178e68f60b5d718 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 08:36:23.986703) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 08:36:23.986971) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 08:36:24.007807) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-19 08:36:24.008113) ========= /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.IMuiCwMj5B/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.vcVnIgXPF1/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 08:36:24 INFO - MultiFileLogger online at 20160519 08:36:24 in /builds/slave/test 08:36:24 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 08:36:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:36:24 INFO - {'append_to_log': False, 08:36:24 INFO - 'base_work_dir': '/builds/slave/test', 08:36:24 INFO - 'blob_upload_branch': 'Fx-Team', 08:36:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:36:24 INFO - 'branch': 'Fx-Team', 08:36:24 INFO - 'buildbot_json_path': 'buildprops.json', 08:36:24 INFO - 'config_files': ('talos/mac_config.py',), 08:36:24 INFO - 'default_actions': ('clobber', 08:36:24 INFO - 'read-buildbot-config', 08:36:24 INFO - 'download-and-extract', 08:36:24 INFO - 'populate-webroot', 08:36:24 INFO - 'create-virtualenv', 08:36:24 INFO - 'install', 08:36:24 INFO - 'run-tests'), 08:36:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:36:24 INFO - 'download_minidump_stackwalk': True, 08:36:24 INFO - 'download_symbols': 'ondemand', 08:36:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:36:24 INFO - 'tooltool.py': '/tools/tooltool.py', 08:36:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:36:24 INFO - '/tools/misc-python/virtualenv.py')}, 08:36:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:36:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:36:24 INFO - 'installer_path': 'installer.exe', 08:36:24 INFO - 'log_level': 'info', 08:36:24 INFO - 'log_name': 'talos', 08:36:24 INFO - 'log_to_console': True, 08:36:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:36:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:36:24 INFO - 'opt_config_files': (), 08:36:24 INFO - 'pip_index': False, 08:36:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:36:24 INFO - 'cmd': ('bash', 08:36:24 INFO - '-c', 08:36:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:36:24 INFO - 'enabled': True, 08:36:24 INFO - 'halt_on_failure': False, 08:36:24 INFO - 'name': 'check_screen_resolution'},), 08:36:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:36:24 INFO - 'cmd': ('bash', 08:36:24 INFO - '-c', 08:36:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:36:24 INFO - 'enabled': True, 08:36:24 INFO - 'halt_on_failure': False, 08:36:24 INFO - 'name': 'check_screen_resolution'},), 08:36:24 INFO - 'run_cmd_checks_enabled': True, 08:36:24 INFO - 'sps_profile': False, 08:36:24 INFO - 'sps_profile_interval': 0, 08:36:24 INFO - 'suite': 'dromaeojs', 08:36:24 INFO - 'system_bits': '32', 08:36:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:36:24 INFO - 'title': 't-yosemite-r7-0131', 08:36:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:36:24 INFO - 'use_talos_json': True, 08:36:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:36:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:36:24 INFO - 'work_dir': 'build'} 08:36:24 INFO - ##### 08:36:24 INFO - ##### Running clobber step. 08:36:24 INFO - ##### 08:36:24 INFO - Running pre-action listener: _resource_record_pre_action 08:36:24 INFO - Running main action method: clobber 08:36:24 INFO - rmtree: /builds/slave/test/build 08:36:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:36:26 INFO - Running post-action listener: _resource_record_post_action 08:36:26 INFO - ##### 08:36:26 INFO - ##### Finished clobber step (success) 08:36:26 INFO - ##### 08:36:26 INFO - ##### 08:36:26 INFO - ##### Running read-buildbot-config step. 08:36:26 INFO - ##### 08:36:26 INFO - Running pre-action listener: _resource_record_pre_action 08:36:26 INFO - Running main action method: read_buildbot_config 08:36:26 INFO - Using buildbot properties: 08:36:26 INFO - { 08:36:26 INFO - "project": "", 08:36:26 INFO - "product": "firefox", 08:36:26 INFO - "script_repo_revision": "production", 08:36:26 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:36:26 INFO - "repository": "", 08:36:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:36:26 INFO - "buildid": "20160519074355", 08:36:26 INFO - "pgo_build": "False", 08:36:26 INFO - "basedir": "/builds/slave/test", 08:36:26 INFO - "buildnumber": 778, 08:36:26 INFO - "slavename": "t-yosemite-r7-0131", 08:36:26 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 08:36:26 INFO - "platform": "yosemite_r7", 08:36:26 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:36:26 INFO - "branch": "fx-team", 08:36:26 INFO - "repo_path": "integration/fx-team", 08:36:26 INFO - "revision": "e8112fba80d64c7c6615c9019178e68f60b5d718", 08:36:26 INFO - "stage_platform": "macosx64", 08:36:26 INFO - "builduid": "322091999a014eb6bed730c7e879dee1", 08:36:26 INFO - "slavebuilddir": "test" 08:36:26 INFO - } 08:36:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 08:36:26 INFO - Running post-action listener: _resource_record_post_action 08:36:26 INFO - ##### 08:36:26 INFO - ##### Finished read-buildbot-config step (success) 08:36:26 INFO - ##### 08:36:26 INFO - ##### 08:36:26 INFO - ##### Running download-and-extract step. 08:36:26 INFO - ##### 08:36:26 INFO - Running pre-action listener: _resource_record_pre_action 08:36:26 INFO - Running main action method: download_and_extract 08:36:26 INFO - mkdir: /builds/slave/test/build/tests 08:36:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:26 INFO - https://queue.taskcluster.net/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:36:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:36:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 08:36:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/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 08:36:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/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 08:36:28 INFO - Downloaded 1199 bytes. 08:36:28 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 08:36:28 INFO - Using the following test package requirements: 08:36:28 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:36:28 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:36:28 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 08:36:28 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:36:28 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 08:36:28 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:36:28 INFO - u'jsshell-mac.zip'], 08:36:28 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:36:28 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 08:36:28 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 08:36:28 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:36:28 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 08:36:28 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:36:28 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 08:36:28 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:36:28 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 08:36:28 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 08:36:28 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 08:36:28 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:36: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')]} 08:36:28 INFO - https://queue.taskcluster.net/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:36:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:36:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:36:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/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 08:36:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/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 08:36:29 INFO - Downloaded 18628942 bytes. 08:36:29 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'] 08:36:29 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 08:36:32 INFO - Return code: 0 08:36:32 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 08:36:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:32 INFO - https://queue.taskcluster.net/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:36:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:36:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 08:36:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/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 08:36:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/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 08:36:33 INFO - Downloaded 18628942 bytes. 08:36:33 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'] 08:36:33 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 08:36:37 INFO - Return code: 0 08:36:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:37 INFO - https://queue.taskcluster.net/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:36:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:36:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 08:36:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/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 08:36:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/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 08:36:38 INFO - Downloaded 10840194 bytes. 08:36:38 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'] 08:36:38 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 08:36:39 INFO - Return code: 0 08:36:39 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')]} 08:36:39 INFO - https://queue.taskcluster.net/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:36:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:36:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:36:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 08:36:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_hfS7GOSI-9p_5pA8QWdA/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 08:36:42 INFO - Downloaded 110311977 bytes. 08:36:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:36:42 INFO - mkdir: /builds/slave/test/properties 08:36:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:36:42 INFO - Writing to file /builds/slave/test/properties/build_url 08:36:42 INFO - Contents: 08:36:42 INFO - build_url:https://queue.taskcluster.net/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 08:36:43 INFO - Running post-action listener: _resource_record_post_action 08:36:43 INFO - Running post-action listener: set_extra_try_arguments 08:36:43 INFO - ##### 08:36:43 INFO - ##### Finished download-and-extract step (success) 08:36:43 INFO - ##### 08:36:43 INFO - ##### 08:36:43 INFO - ##### Running populate-webroot step. 08:36:43 INFO - ##### 08:36:43 INFO - Running pre-action listener: _resource_record_pre_action 08:36:43 INFO - Running main action method: populate_webroot 08:36:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:36:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:36:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:36:43 INFO - '--tppagecycles', 08:36:43 INFO - '7'], 08:36:43 INFO - 'tests': ['tsvgm']}}, 08:36:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:36:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:36:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:36:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:36:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:36:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:36:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:36:43 INFO - 'talos_options': ['--disable-e10s'], 08:36:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:36:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:36:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:36:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:36:43 INFO - 'talos_options': ['--disable-e10s'], 08:36:43 INFO - 'tests': ['damp', 'tps']}, 08:36:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:36:43 INFO - 'tests': ['damp', 'tps']}, 08:36:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:36:43 INFO - 'tests': ['dromaeo_dom']}, 08:36:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:36:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:36:43 INFO - 'tests': ['a11yr', 08:36:43 INFO - 'ts_paint', 08:36:43 INFO - 'tpaint', 08:36:43 INFO - 'sessionrestore', 08:36:43 INFO - 'sessionrestore_no_auto_restore', 08:36:43 INFO - 'tabpaint']}, 08:36:43 INFO - 'other-e10s': {'tests': ['a11yr', 08:36:43 INFO - 'ts_paint', 08:36:43 INFO - 'tpaint', 08:36:43 INFO - 'sessionrestore', 08:36:43 INFO - 'sessionrestore_no_auto_restore', 08:36:43 INFO - 'tabpaint']}, 08:36:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:36:43 INFO - 'ts_paint', 08:36:43 INFO - 'tpaint', 08:36:43 INFO - 'sessionrestore', 08:36:43 INFO - 'sessionrestore_no_auto_restore', 08:36:43 INFO - 'tabpaint']}, 08:36:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:36:43 INFO - 'ts_paint', 08:36:43 INFO - 'tpaint', 08:36:43 INFO - 'sessionrestore', 08:36:43 INFO - 'sessionrestore_no_auto_restore', 08:36:43 INFO - 'tabpaint']}, 08:36:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:36:43 INFO - 'tests': ['a11yr', 08:36:43 INFO - 'ts_paint', 08:36:43 INFO - 'tpaint', 08:36:43 INFO - 'sessionrestore', 08:36:43 INFO - 'sessionrestore_no_auto_restore', 08:36:43 INFO - 'tabpaint']}, 08:36:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:36:43 INFO - 'tests': ['a11yr', 08:36:43 INFO - 'ts_paint', 08:36:43 INFO - 'tpaint', 08:36:43 INFO - 'sessionrestore', 08:36:43 INFO - 'sessionrestore_no_auto_restore', 08:36:43 INFO - 'tabpaint']}, 08:36:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:36:43 INFO - 'tests': ['tsvgx', 08:36:43 INFO - 'tsvgr_opacity', 08:36:43 INFO - 'tart', 08:36:43 INFO - 'tscrollx', 08:36:43 INFO - 'cart']}, 08:36:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:36:43 INFO - 'tsvgr_opacity', 08:36:43 INFO - 'tart', 08:36:43 INFO - 'tscrollx', 08:36:43 INFO - 'cart']}, 08:36:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:36:43 INFO - 'talos_options': ['--disable-e10s'], 08:36:43 INFO - 'tests': ['tp5o']}, 08:36:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:36:43 INFO - 'tests': ['tp5o']}, 08:36:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:36:43 INFO - 'talos_options': ['--disable-e10s', 08:36:43 INFO - '--xperf_path', 08:36:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:36:43 INFO - 'tests': ['tp5n']}, 08:36:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:36:43 INFO - 'talos_options': ['--xperf_path', 08:36:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:36:43 INFO - 'tests': ['tp5n']}}, 08:36:43 INFO - 'talos.zip': {'path': '', 08:36:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:36:43 INFO - Running post-action listener: _resource_record_post_action 08:36:43 INFO - ##### 08:36:43 INFO - ##### Finished populate-webroot step (success) 08:36:43 INFO - ##### 08:36:43 INFO - ##### 08:36:43 INFO - ##### Running create-virtualenv step. 08:36:43 INFO - ##### 08:36:43 INFO - Running pre-action listener: _resource_record_pre_action 08:36:43 INFO - Running main action method: create_virtualenv 08:36:43 INFO - Creating virtualenv /builds/slave/test/build/venv 08:36:43 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 08:36:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:36:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:36:43 INFO - Using real prefix '/tools/python27' 08:36:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:36:44 INFO - Installing distribute.............................................................................................................................................................................................done. 08:36:47 INFO - Installing pip.................done. 08:36:47 INFO - Return code: 0 08:36:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:36:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:36:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:47 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 0x10f136a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f13c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda2ad93e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efdad30>, '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 0x10edfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efd1b20>, '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.IMuiCwMj5B/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.vcVnIgXPF1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 08:36:47 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 08:36:47 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 08:36:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMuiCwMj5B/Render', 08:36:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:47 INFO - 'HOME': '/Users/cltbld', 08:36:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:47 INFO - 'LOGNAME': 'cltbld', 08:36:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:47 INFO - 'MOZ_NO_REMOTE': '1', 08:36:47 INFO - 'NO_EM_RESTART': '1', 08:36:47 INFO - 'PAGER': '/bin/cat', 08:36:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:47 INFO - 'PWD': '/builds/slave/test', 08:36:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:47 INFO - 'SHELL': '/bin/bash', 08:36:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vcVnIgXPF1/Listeners', 08:36:47 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:36:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:47 INFO - 'USER': 'cltbld', 08:36:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:47 INFO - 'XPC_FLAGS': '0x0', 08:36:47 INFO - 'XPC_SERVICE_NAME': '0', 08:36:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:36:47 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 08:36:47 INFO - Cleaning up... 08:36:47 INFO - Return code: 0 08:36:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:36:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:36:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:47 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 0x10f136a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f13c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda2ad93e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efdad30>, '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 0x10edfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efd1b20>, '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.IMuiCwMj5B/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.vcVnIgXPF1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 08:36:47 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 08:36:47 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 08:36:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMuiCwMj5B/Render', 08:36:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:47 INFO - 'HOME': '/Users/cltbld', 08:36:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:47 INFO - 'LOGNAME': 'cltbld', 08:36:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:47 INFO - 'MOZ_NO_REMOTE': '1', 08:36:47 INFO - 'NO_EM_RESTART': '1', 08:36:47 INFO - 'PAGER': '/bin/cat', 08:36:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:47 INFO - 'PWD': '/builds/slave/test', 08:36:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:47 INFO - 'SHELL': '/bin/bash', 08:36:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vcVnIgXPF1/Listeners', 08:36:47 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:36:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:47 INFO - 'USER': 'cltbld', 08:36:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:47 INFO - 'XPC_FLAGS': '0x0', 08:36:47 INFO - 'XPC_SERVICE_NAME': '0', 08:36:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:36:47 INFO - Downloading/unpacking psutil>=3.1.1 08:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:36:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:36:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:36:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:36:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:36:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:36:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:36:50 INFO - Installing collected packages: psutil 08:36:50 INFO - Running setup.py install for psutil 08:36:50 INFO - building 'psutil._psutil_osx' extension 08:36:50 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 08:36:51 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 08:36:51 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 08:36:51 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 08:36:51 INFO - building 'psutil._psutil_posix' extension 08:36:51 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 08:36:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:36:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:36:51 INFO - ^ 08:36:51 INFO - 1 warning generated. 08:36:51 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 08:36:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:36:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:36:51 INFO - Successfully installed psutil 08:36:51 INFO - Cleaning up... 08:36:51 INFO - Return code: 0 08:36:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:36:51 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')]} 08:36:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:36:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:51 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')]} 08:36:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:36:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:51 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 0x10f136a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f13c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda2ad93e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efdad30>, '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 0x10edfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efd1b20>, '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.IMuiCwMj5B/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.vcVnIgXPF1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 08:36:51 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 08:36:51 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 08:36:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMuiCwMj5B/Render', 08:36:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:51 INFO - 'HOME': '/Users/cltbld', 08:36:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:51 INFO - 'LOGNAME': 'cltbld', 08:36:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:51 INFO - 'MOZ_NO_REMOTE': '1', 08:36:51 INFO - 'NO_EM_RESTART': '1', 08:36:51 INFO - 'PAGER': '/bin/cat', 08:36:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:51 INFO - 'PWD': '/builds/slave/test', 08:36:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:51 INFO - 'SHELL': '/bin/bash', 08:36:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vcVnIgXPF1/Listeners', 08:36:51 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:36:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:51 INFO - 'USER': 'cltbld', 08:36:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:51 INFO - 'XPC_FLAGS': '0x0', 08:36:51 INFO - 'XPC_SERVICE_NAME': '0', 08:36:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:36:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:36:52 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 08:36:52 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 08:36:52 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 08:36:52 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 08:36:52 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 08:36:52 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 08:36:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:36:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:36:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:36:54 INFO - Installing collected packages: mozsystemmonitor 08:36:54 INFO - Running setup.py install for mozsystemmonitor 08:36:54 INFO - Successfully installed mozsystemmonitor 08:36:54 INFO - Cleaning up... 08:36:54 INFO - Return code: 0 08:36:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:36:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:36:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:36:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:54 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 0x10f136a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f13c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda2ad93e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efdad30>, '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 0x10edfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efd1b20>, '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.IMuiCwMj5B/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.vcVnIgXPF1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 08:36:54 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 08:36:54 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 08:36:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMuiCwMj5B/Render', 08:36:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:54 INFO - 'HOME': '/Users/cltbld', 08:36:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:54 INFO - 'LOGNAME': 'cltbld', 08:36:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:54 INFO - 'MOZ_NO_REMOTE': '1', 08:36:54 INFO - 'NO_EM_RESTART': '1', 08:36:54 INFO - 'PAGER': '/bin/cat', 08:36:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:54 INFO - 'PWD': '/builds/slave/test', 08:36:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:54 INFO - 'SHELL': '/bin/bash', 08:36:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vcVnIgXPF1/Listeners', 08:36:54 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:36:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:54 INFO - 'USER': 'cltbld', 08:36:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:54 INFO - 'XPC_FLAGS': '0x0', 08:36:54 INFO - 'XPC_SERVICE_NAME': '0', 08:36:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:36:55 INFO - Downloading/unpacking blobuploader==1.2.4 08:36:55 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 08:36:55 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 08:36:55 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 08:36:55 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 08:36:55 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 08:36:55 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 08:36:57 INFO - Downloading blobuploader-1.2.4.tar.gz 08:36:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:36:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:36: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 08:36: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 08:36: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 08:36: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 08:36: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 08:36: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 08:36:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:36:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:36:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:36:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:36:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:36:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:36:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:36:58 INFO - Downloading docopt-0.6.1.tar.gz 08:36:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:36:58 INFO - Installing collected packages: blobuploader, requests, docopt 08:36:58 INFO - Running setup.py install for blobuploader 08:36:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:36:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:36:58 INFO - Running setup.py install for requests 08:36:59 INFO - Running setup.py install for docopt 08:36:59 INFO - Successfully installed blobuploader requests docopt 08:36:59 INFO - Cleaning up... 08:36:59 INFO - Return code: 0 08:36:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:36:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:36:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:36:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:36:59 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 0x10f136a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f13c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda2ad93e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efdad30>, '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 0x10edfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efd1b20>, '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.IMuiCwMj5B/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.vcVnIgXPF1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 08:36:59 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 08:36:59 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 08:36:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMuiCwMj5B/Render', 08:36:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:36:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:36:59 INFO - 'HOME': '/Users/cltbld', 08:36:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:36:59 INFO - 'LOGNAME': 'cltbld', 08:36:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:36:59 INFO - 'MOZ_NO_REMOTE': '1', 08:36:59 INFO - 'NO_EM_RESTART': '1', 08:36:59 INFO - 'PAGER': '/bin/cat', 08:36:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:36:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:36:59 INFO - 'PWD': '/builds/slave/test', 08:36:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:36:59 INFO - 'SHELL': '/bin/bash', 08:36:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vcVnIgXPF1/Listeners', 08:36:59 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:36:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:36:59 INFO - 'USER': 'cltbld', 08:36:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:36:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:36:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:36:59 INFO - 'XPC_FLAGS': '0x0', 08:36:59 INFO - 'XPC_SERVICE_NAME': '0', 08:36:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:36:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:36:59 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-79zMQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:36:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:36:59 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-lhmLBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:37:00 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-843pRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:37:00 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-RmxhPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:37:00 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Wtl6Ih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:37:00 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-O1LLWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:37:00 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-9E1fsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:37:00 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-rZw53U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:37:00 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-PCP5S3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:37:00 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-zsxwrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:37:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:37:00 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-loHrbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:37:01 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-LCw3yD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:37:01 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-BFS9hH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:37:01 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-c8SOqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:37:01 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-j6l4Rl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:37:01 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-EWhjdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:37:01 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-G2Sq5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:37:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:37:01 INFO - Running setup.py install for manifestparser 08:37:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:37:01 INFO - Running setup.py install for mozcrash 08:37:01 INFO - Running setup.py install for mozdebug 08:37:02 INFO - Running setup.py install for mozdevice 08:37:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:37:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:37:02 INFO - Running setup.py install for mozfile 08:37:02 INFO - Running setup.py install for mozhttpd 08:37:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:37:02 INFO - Running setup.py install for mozinfo 08:37:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:37:02 INFO - Running setup.py install for mozInstall 08:37:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:37:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:37:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:37:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:37:02 INFO - Running setup.py install for mozleak 08:37:02 INFO - Running setup.py install for mozlog 08:37:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:37:03 INFO - Running setup.py install for moznetwork 08:37:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:37:03 INFO - Running setup.py install for mozprocess 08:37:03 INFO - Running setup.py install for mozprofile 08:37:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:37:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:37:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:37:03 INFO - Running setup.py install for mozrunner 08:37:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:37:03 INFO - Running setup.py install for mozscreenshot 08:37:03 INFO - Running setup.py install for moztest 08:37:04 INFO - Running setup.py install for mozversion 08:37:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:37:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:37:04 INFO - Cleaning up... 08:37:04 INFO - Return code: 0 08:37:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:37: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')]} 08:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37: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')]} 08:37:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:04 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 0x10f136a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f13c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda2ad93e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efdad30>, '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 0x10edfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efd1b20>, '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.IMuiCwMj5B/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.vcVnIgXPF1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 08:37:04 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 08:37:04 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 08:37:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMuiCwMj5B/Render', 08:37:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:04 INFO - 'HOME': '/Users/cltbld', 08:37:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:04 INFO - 'LOGNAME': 'cltbld', 08:37:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:04 INFO - 'MOZ_NO_REMOTE': '1', 08:37:04 INFO - 'NO_EM_RESTART': '1', 08:37:04 INFO - 'PAGER': '/bin/cat', 08:37:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:04 INFO - 'PWD': '/builds/slave/test', 08:37:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:04 INFO - 'SHELL': '/bin/bash', 08:37:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vcVnIgXPF1/Listeners', 08:37:04 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:37:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:04 INFO - 'USER': 'cltbld', 08:37:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:04 INFO - 'XPC_FLAGS': '0x0', 08:37:04 INFO - 'XPC_SERVICE_NAME': '0', 08:37:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:37:04 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-EPIOKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:37:04 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)) 08:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:37:04 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-_nycIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:37:04 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)) 08:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:37:04 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-LOUSzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:37:04 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)) 08:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:37:04 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-dNNkC_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:37:04 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)) 08:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:37:04 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Z4t_G9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:37:05 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)) 08:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:37:05 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-qzv6AN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:37:05 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)) 08:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:37:05 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-xOb6kK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:37:05 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)) 08:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:37:05 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-l2O8a4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:37:05 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)) 08:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:37:05 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-5QCA9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:37:05 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)) 08:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:37:05 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-9GKZu7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:37:05 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)) 08:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:37:05 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-x53aSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:37:05 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)) 08:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:37:05 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-T45D7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:37:05 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)) 08:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:37:05 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-V8qn0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:37:05 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)) 08:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:37:05 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-lWJwBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:37:06 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)) 08:37:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:37:06 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-elZFOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:37:06 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)) 08:37:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:37:06 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Mx61nJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:37:06 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)) 08:37:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:37:06 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-_MbIFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:37:06 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)) 08:37:06 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)) 08:37:06 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)) 08:37:06 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)) 08:37:06 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)) 08:37:06 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)) 08:37:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:37: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 08:37: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 08:37: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 08:37: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 08:37: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 08:37: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 08:37:08 INFO - Downloading blessings-1.6.tar.gz 08:37:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:37:08 INFO - Installing collected packages: blessings 08:37:08 INFO - Running setup.py install for blessings 08:37:09 INFO - Successfully installed blessings 08:37:09 INFO - Cleaning up... 08:37:09 INFO - Return code: 0 08:37:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:37:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:37:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:37:09 INFO - Reading from file tmpfile_stdout 08:37:09 INFO - Current package versions: 08:37:09 INFO - blessings == 1.6 08:37:09 INFO - blobuploader == 1.2.4 08:37:09 INFO - docopt == 0.6.1 08:37:09 INFO - manifestparser == 1.1 08:37:09 INFO - mozInstall == 1.12 08:37:09 INFO - mozcrash == 0.17 08:37:09 INFO - mozdebug == 0.1 08:37:09 INFO - mozdevice == 0.48 08:37:09 INFO - mozfile == 1.2 08:37:09 INFO - mozhttpd == 0.7 08:37:09 INFO - mozinfo == 0.9 08:37:09 INFO - mozleak == 0.1 08:37:09 INFO - mozlog == 3.2 08:37:09 INFO - moznetwork == 0.27 08:37:09 INFO - mozprocess == 0.22 08:37:09 INFO - mozprofile == 0.28 08:37:09 INFO - mozrunner == 6.11 08:37:09 INFO - mozscreenshot == 0.1 08:37:09 INFO - mozsystemmonitor == 0.3 08:37:09 INFO - moztest == 0.7 08:37:09 INFO - mozversion == 1.4 08:37:09 INFO - psutil == 3.1.1 08:37:09 INFO - requests == 1.2.3 08:37:09 INFO - wsgiref == 0.1.2 08:37:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:37:09 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')]} 08:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:09 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')]} 08:37:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:09 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 0x10f136a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f13c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda2ad93e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efdad30>, '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 0x10edfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efd1b20>, '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.IMuiCwMj5B/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.vcVnIgXPF1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 08:37:09 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 08:37:09 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 08:37:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMuiCwMj5B/Render', 08:37:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:09 INFO - 'HOME': '/Users/cltbld', 08:37:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:09 INFO - 'LOGNAME': 'cltbld', 08:37:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:09 INFO - 'MOZ_NO_REMOTE': '1', 08:37:09 INFO - 'NO_EM_RESTART': '1', 08:37:09 INFO - 'PAGER': '/bin/cat', 08:37:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:09 INFO - 'PWD': '/builds/slave/test', 08:37:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:09 INFO - 'SHELL': '/bin/bash', 08:37:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vcVnIgXPF1/Listeners', 08:37:09 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:37:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:09 INFO - 'USER': 'cltbld', 08:37:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:09 INFO - 'XPC_FLAGS': '0x0', 08:37:09 INFO - 'XPC_SERVICE_NAME': '0', 08:37:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:09 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)) 08:37:09 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)) 08:37:09 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)) 08:37:09 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)) 08:37:09 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)) 08:37:09 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)) 08:37:09 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)) 08:37:09 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)) 08:37:09 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)) 08:37:09 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)) 08:37:09 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)) 08:37:09 INFO - Cleaning up... 08:37:09 INFO - Return code: 0 08:37:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:37:09 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')]} 08:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:09 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')]} 08:37:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:09 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 0x10f136a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f13c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda2ad93e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efdad30>, '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 0x10edfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efd1b20>, '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.IMuiCwMj5B/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.vcVnIgXPF1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 08:37:09 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 08:37:09 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 08:37:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMuiCwMj5B/Render', 08:37:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:09 INFO - 'HOME': '/Users/cltbld', 08:37:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:09 INFO - 'LOGNAME': 'cltbld', 08:37:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:09 INFO - 'MOZ_NO_REMOTE': '1', 08:37:09 INFO - 'NO_EM_RESTART': '1', 08:37:09 INFO - 'PAGER': '/bin/cat', 08:37:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:09 INFO - 'PWD': '/builds/slave/test', 08:37:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:09 INFO - 'SHELL': '/bin/bash', 08:37:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vcVnIgXPF1/Listeners', 08:37:09 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:37:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:09 INFO - 'USER': 'cltbld', 08:37:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:09 INFO - 'XPC_FLAGS': '0x0', 08:37:09 INFO - 'XPC_SERVICE_NAME': '0', 08:37:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:09 INFO - Downloading/unpacking jsonschema 08:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:37:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:37:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:37:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:37:12 INFO - Downloading/unpacking functools32 (from jsonschema) 08:37:12 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 08:37:12 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 08:37:12 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 08:37:12 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 08:37:12 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 08:37:12 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 08:37:12 INFO - Downloading functools32-3.2.3-2.tar.gz 08:37:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:37:12 INFO - warning: no files found matching '*.txt' 08:37:12 INFO - no previously-included directories found matching 'build' 08:37:12 INFO - no previously-included directories found matching 'dist' 08:37:12 INFO - no previously-included directories found matching '.git*' 08:37:12 INFO - Installing collected packages: jsonschema, functools32 08:37:13 INFO - Running setup.py install for functools32 08:37:13 INFO - warning: no files found matching '*.txt' 08:37:13 INFO - no previously-included directories found matching 'build' 08:37:13 INFO - no previously-included directories found matching 'dist' 08:37:13 INFO - no previously-included directories found matching '.git*' 08:37:13 INFO - Successfully installed jsonschema functools32 08:37:13 INFO - Cleaning up... 08:37:13 INFO - Return code: 0 08:37:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:37: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')]} 08:37:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:37:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37: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')]} 08:37:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:37:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:37:13 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 0x10f136a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f13c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda2ad93e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efdad30>, '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 0x10edfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efd1b20>, '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.IMuiCwMj5B/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.vcVnIgXPF1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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 08:37:13 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 08:37:13 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 08:37:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMuiCwMj5B/Render', 08:37:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:13 INFO - 'HOME': '/Users/cltbld', 08:37:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:13 INFO - 'LOGNAME': 'cltbld', 08:37:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:13 INFO - 'MOZ_NO_REMOTE': '1', 08:37:13 INFO - 'NO_EM_RESTART': '1', 08:37:13 INFO - 'PAGER': '/bin/cat', 08:37:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:13 INFO - 'PWD': '/builds/slave/test', 08:37:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:13 INFO - 'SHELL': '/bin/bash', 08:37:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vcVnIgXPF1/Listeners', 08:37:13 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:37:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:13 INFO - 'USER': 'cltbld', 08:37:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:13 INFO - 'XPC_FLAGS': '0x0', 08:37:13 INFO - 'XPC_SERVICE_NAME': '0', 08:37:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:37:13 INFO - Downloading/unpacking flake8 08:37:13 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 08:37:13 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 08:37:13 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 08:37:13 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 08:37:13 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 08:37:13 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 08:37:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:37:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:37: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 08:37: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 08:37: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 08:37: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 08:37: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 08:37: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 08:37:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:37:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:37:16 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 08:37:16 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 08:37:16 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 08:37:16 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 08:37:16 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 08:37:16 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 08:37:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:37:16 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 08:37:16 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 08:37:16 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 08:37:16 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 08:37:16 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 08:37:16 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 08:37:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:37:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:37:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:37:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:37:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:37:17 INFO - no previously-included directories found matching 'docs/_build' 08:37:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:37:17 INFO - Running setup.py install for pep8 08:37:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:37:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:37:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:37:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:37:17 INFO - no previously-included directories found matching 'docs/_build' 08:37:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:37:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:37:17 INFO - Cleaning up... 08:37:17 INFO - Return code: 0 08:37:17 INFO - Running post-action listener: _resource_record_post_action 08:37:17 INFO - Running post-action listener: _start_resource_monitoring 08:37:17 INFO - Starting resource monitoring. 08:37:17 INFO - ##### 08:37:17 INFO - ##### Finished create-virtualenv step (success) 08:37:17 INFO - ##### 08:37:17 INFO - ##### 08:37:17 INFO - ##### Running install step. 08:37:17 INFO - ##### 08:37:17 INFO - Running pre-action listener: _resource_record_pre_action 08:37:17 INFO - Running main action method: install 08:37:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:37:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:37:17 INFO - Reading from file tmpfile_stdout 08:37:17 INFO - Detecting whether we're running mozinstall >=1.0... 08:37:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:37:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:37:17 INFO - Reading from file tmpfile_stdout 08:37:17 INFO - Output received: 08:37:17 INFO - Usage: mozinstall [options] installer 08:37:17 INFO - Options: 08:37:17 INFO - -h, --help show this help message and exit 08:37:17 INFO - -d DEST, --destination=DEST 08:37:17 INFO - Directory to install application into. [default: 08:37:17 INFO - "/builds/slave/test"] 08:37:17 INFO - --app=APP Application being installed. [default: firefox] 08:37:17 INFO - mkdir: /builds/slave/test/build/application 08:37:17 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'] 08:37:17 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 08:37:51 INFO - Reading from file tmpfile_stdout 08:37:51 INFO - Output received: 08:37:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:37:51 INFO - Running post-action listener: _resource_record_post_action 08:37:51 INFO - ##### 08:37:51 INFO - ##### Finished install step (success) 08:37:51 INFO - ##### 08:37:51 INFO - ##### 08:37:51 INFO - ##### Running run-tests step. 08:37:51 INFO - ##### 08:37:51 INFO - Running pre-action listener: _resource_record_pre_action 08:37:51 INFO - Running main action method: run_tests 08:37:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:37:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:37:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:37:51 INFO - 2016-05-19 08:37:51.590 screenresolution[1638:7703] starting screenresolution argv=screenresolution get 08:37:51 INFO - 2016-05-19 08:37:51.592 screenresolution[1638:7703] Display 0: 1600x1200x32@60 08:37:51 INFO - 2016-05-19 08:37:51.598 screenresolution[1639:7705] starting screenresolution argv=screenresolution list 08:37:51 INFO - Available Modes on Display 0 08:37:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:37:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:37:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:37:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:37:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:37:51 INFO - Intel Iris: 08:37:51 INFO - Chipset Model: Intel Iris 08:37:51 INFO - Type: GPU 08:37:51 INFO - Bus: Built-In 08:37:51 INFO - VRAM (Dynamic, Max): 1536 MB 08:37:51 INFO - Vendor: Intel (0x8086) 08:37:51 INFO - Device ID: 0x0a2e 08:37:51 INFO - Revision ID: 0x0009 08:37:51 INFO - Displays: 08:37:51 INFO - Display: 08:37:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:37:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:37:51 INFO - Display Serial Number: 1600x1200 60 08:37:51 INFO - Main Display: Yes 08:37:51 INFO - Mirror: Off 08:37:51 INFO - Online: Yes 08:37:51 INFO - Rotation: Supported 08:37:51 INFO - Return code: 0 08:37:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:37:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:37:51 INFO - Python 2.7.3 08:37:51 INFO - Return code: 0 08:37:51 INFO - grabbing minidump binary from tooltool 08:37:51 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')]} 08:37:51 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 0x10efdad30>, '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 0x10edfedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efd1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:37:51 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 08:37:51 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 08:37:51 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 08:37:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:37:51 INFO - Return code: 0 08:37:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:37:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:37:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:37:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:37:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:37:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:37:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:37:53 INFO - Return code: 0 08:37:53 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/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0131', '--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 08:37:53 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/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0131 --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 08:37:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IMuiCwMj5B/Render', 08:37:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:37:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:37:53 INFO - 'HOME': '/Users/cltbld', 08:37:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:37:53 INFO - 'LOGNAME': 'cltbld', 08:37:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:37:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:37:53 INFO - 'MOZ_NO_REMOTE': '1', 08:37:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:37:53 INFO - 'NO_EM_RESTART': '1', 08:37:53 INFO - 'PAGER': '/bin/cat', 08:37:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:37:53 INFO - 'PWD': '/builds/slave/test', 08:37:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:37:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:37:53 INFO - 'SHELL': '/bin/bash', 08:37:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vcVnIgXPF1/Listeners', 08:37:53 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:37:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:37:53 INFO - 'USER': 'cltbld', 08:37:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:37:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:37:53 INFO - 'XPC_FLAGS': '0x0', 08:37:53 INFO - 'XPC_SERVICE_NAME': '0', 08:37:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:37:53 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/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0131', '--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 08:37:53 INFO - mozversion application_buildid: 20160519074355 08:37:53 INFO - mozversion application_changeset: e8112fba80d64c7c6615c9019178e68f60b5d718 08:37:53 INFO - mozversion application_display_name: Nightly 08:37:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:37:53 INFO - mozversion application_name: Firefox 08:37:53 INFO - mozversion application_remotingname: firefox 08:37:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 08:37:53 INFO - mozversion application_vendor: Mozilla 08:37:53 INFO - mozversion application_version: 49.0a1 08:37:53 INFO - mozversion platform_buildid: 20160519074355 08:37:53 INFO - mozversion platform_changeset: e8112fba80d64c7c6615c9019178e68f60b5d718 08:37:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 08:37:53 INFO - mozversion platform_version: 49.0a1 08:37:53 INFO - using testdate: 1463672273 08:37:53 INFO - actual date: 1463672273 08:37:53 INFO - starting webserver on 'localhost:49285' 08:37:53 INFO - SUITE-START | Running 2 tests 08:37:53 INFO - TEST-START | dromaeo_css 08:37:53 INFO - operating with platform_type : mac_ 08:37:53 INFO - Initialising browser for dromaeo_css test... 08:37:53 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpIhHNor/profile http://localhost:49285/getInfo.html) 08:37:55 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 08:37:55 INFO - PROCESS | 1657 | colorDepth:24 08:37:55 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 08:37:55 INFO - PROCESS | 1657 | __metrics 08:37:55 INFO - PROCESS | 1657 | 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" 08:37:55 ERROR - PROCESS | 1657 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:37:56 INFO - TEST-INFO | 1657: exit 0 08:37:56 INFO - Browser initialized. 08:37:56 INFO - Running cycle 1/1 for dromaeo_css test... 08:37:56 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpIhHNor/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:38:08 INFO - PROCESS | 1659 | RSS: Main: 238977024 08:38:08 INFO - PROCESS | 1659 | 08:38:59 INFO - PROCESS | 1659 | 1463672339525 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] 08:41:09 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49285/tests/dromaeo/cssquery-ext.html) 08:41:11 INFO - PROCESS | 1659 | RSS: Main: 328167424 08:41:11 INFO - PROCESS | 1659 | 08:44:13 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-ext.html (next: http://localhost:49285/tests/dromaeo/cssquery-jquery.html) 08:44:14 INFO - PROCESS | 1659 | RSS: Main: 376172544 08:44:14 INFO - PROCESS | 1659 | 08:47:16 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49285/tests/dromaeo/cssquery-mootools.html) 08:47:17 INFO - PROCESS | 1659 | RSS: Main: 368926720 08:47:17 INFO - PROCESS | 1659 | 08:50:19 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49285/tests/dromaeo/cssquery-prototype.html) 08:50:20 INFO - PROCESS | 1659 | RSS: Main: 368541696 08:50:20 INFO - PROCESS | 1659 | 08:53:23 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49285/tests/dromaeo/cssquery-yui.html) 08:53:24 INFO - PROCESS | 1659 | RSS: Main: 340799488 08:53:24 INFO - PROCESS | 1659 | 08:56:27 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-yui.html (next: http://localhost:49285/tests/dromaeo/cssquery-dojo.html) 08:56:27 INFO - PROCESS | 1659 | RSS: Main: 430514176 08:56:27 INFO - PROCESS | 1659 | 08:56:27 INFO - PROCESS | 1659 | __start_tp_report 08:56:27 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 08:56:27 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 08:56:27 INFO - PROCESS | 1659 | |i|pagename|runs| 08:56:27 INFO - PROCESS | 1659 | |0;dojo.html;2940.3531223130913;3435.227073908371;3462.5152478652985;3464.6881780639737;3511.5610548681416;5621.662700237988;5647;5657.405972372903;5735.397783232763;5764.798232061884;5718.59969802114;5729.512991395736;5785.884282314351;5788.768449262035;5868.970655146718;5149.948500515003;5219.373675158986;5254.763535640886;5286.497782710637;5366.168243922199;5335.626506144571;5361.678299302054;5374.328208973879;5386.1920711893135;5397.973010134944;5151.76817043232;5190.7404629768735;5208.869778255536;5227.424983251839;5234.502722241381;5195.350581177353;5218.165093585008;5233.633645644806;5253.028189784864;5264.815731449419;4655.697379670344;5058.190689489701;5237.816676416307;5271.42014378418;5293.285406470116;5549.639273447213;5597.608167428281;5662.235598194232;5668.518175955049;5750.827475175752;6323.019931910561;6330.556861019731;6333.98656215008;6347.936520634781;6348.9365106348805;6306.779262725782;6369.426751592379;6379.776707815251;6388.904166437507;6392.20097487814;5716.428357164292;5738.799142030009;5803.5357171426185;5818.8254352369495;5919.881602367928;5814.244148260699;6182.381761823782;6290.433860952537;6329.050642403677;6342.841428964312;6178.505719542516;6181.721822517998;6188.938110618927;6191.164192834;6192.783252586138;6109.890546867989;6205.348438413992;6220.25356957159;6224.875502489925;6311.053341998645;6159.384061593805;6210.254769427697;6217.067439884054;6226.750929962751;6240;6253.1245625612455;6253.624782513064;6270.059491076375;6281.937180628227;6299.874002519924;4872.14737420957;4886.193778026657;4890.168671325883;4891.070696567641;4951.430585482643;3768.3970564709516;4478.216312145361;4490.191765482179;4492.0791237796175;4502.752348620857;5621.156826476062;5622.297212848394;5622.91565626516;5624.29696287964;5634.3802181759975;6251;6257.2491301044165;6261.624302541862;6262.342454042351;6342.714577844008;5653.632513886584;5662.207290979266;5675.574331925122;5680.829575112753;5692.6299790513485;5349.81275655358;5419.864503387368;5428.945710542844;5448.863778405572;5455.536279416215;6276.089966954766;6291.150694656254;6332.050192471166;6395.936040639534;6398.2322121344805;6376.649284289409;6384.585001974857;6388.744450221939;6391.201099862517;6403.391677790602;6281.12064311006;6298.464630506458;6409.7115629795835;6409.9359006411205;6416.422521973045;6150.723217455135;6293.37066293367;6307.085472606538;6323.241211054703;6334.809955701152;6333.714982825692;6337.176167098248;6392.776252831129;6396.808095757135;6418.679066046772;6033.155358249936;6105.004892149239;6111.694415279308;6187.938120618736;6203.2556093269095;6220.657863817533;6229.937700622936;6230.06549017651;6232.781852635136;6261.342559031418;5995.2206213192;6050.757969681164;6064.151018857452;6065.059915713072;6114;5899.911501327398;6047.0929360596265;6097.373446762037;6106.908396374147;6134.926457743815;6056.394360563909;6064.696765161812;6074.3925607438905;6082.878342433038;6112.113743507255 08:56:27 INFO - PROCESS | 1659 | |1;ext.html;498.03381440006393;509.17513627922773;510.56856955872286;521.616611790334;524.9475052494753;31312.53031204531;31902.521462178058;43503.78248108755;44305.113897722025;44359.77820110903;42724.78637606815;45426.77286613562;45488.317675234895;45488.54137503496;45516.77241613787;9812.362196457243;10010.34932729374;10013.249006324519;10013.649522266722;10014.248931330143;8506.149385061506;9712.368696034771;9860.950695246549;9870.21038316933;9889.307748457612;9821.116099551038;9884.950575247114;9891.752706182368;9912.306138570302;9914.801703965952;9820.705378838646;9875.950620246853;9886.209103271756;9886.802263954753;9895.406275623414;9748.561314740853;9837.409755414697;9859.211263098934;9864.161546268575;9867.013298670146;34117.48823767646;41509;42352.36471452931;42445.78777106095;42465.78767106176;3179.682031796825;3297.5878015248095;3330.234046169395;3352.6312105668358;3480.5127282180515;29629.851850740823;29933.550996735066;30057.098287051424;30443;30587.69412305893;2739.055026016021;2753.3942532642786;2770.252031951362;2848.9316506310133;2902.506573882445;521976.3901180508;522301;524130;524938.9999999962;525635;63697.044544331875;63941;64433.67783161148;64555.22170172158;64652;36573.451398229045;37385.06537336534;37511.624883751356;38616;38664.80667596644;14299.64250893736;17375.73936390955;17414.47756567305;17420.303187872596;17427;13081.084324097223;13145.408456619474;13176.802347964789;13180.934095329461;13190.670233244055;17259;19967.400977970683;20179.79820201779;20385.08267127953;20574.224667144972;6478.676066196671;6496.512761542903;7647.426443016796;7654.578998155043;7707.113681926538;909.4361495872602;953.5518306395983;1035.984460233097;1097.3854641400842;1112.093643680398;28652;29498.852505737334;29551.704482954894;30309.393812124075;30437.586549950134;826.900771907375;961.3414810854587;1081.599808071003;1100.6626948257906;1125.454154734953;15215.695686086216;15256.466023689118;15387;15546.611334716496;15599.610009749847;653157;662241;665353;667934;668159;61575.692121539105;62085;62168.067478987854;62223.37776622176;62262;1307.1372893889986;1441.3081720773878;1450.6881020580415;1474.8230212374583;1487.3530014443752;22409.991550380597;29602.851985739933;30407.391852163277;30628.92798752033;31348.843255783577;52403.21395178999;52521;53280;53293.733531332095;53327;502109;503038.9696103138;503435;504159.47920260165;505052;23498.882505588048;26305.73694263033;26982.460350793266;27123.186304410898;27369.72630273672;46467;46784.06431871412;47044;47522.52477475181;48203.75898120487;35223.647763522036;35829.1042723934;36410.63589364073;38185.6181438182;38694;37001.814990924875;37398.252034959696;37819;37920.577905890066;37962.810185948896;32221.838890805397;37845.62154378531;39233.607663922994;39285.2142957145;40889.59110408858;26925.596116057885;32914;33963.49054764131;33965.3206935855;34157.829210853786;23110;24157.792110394763;24885;25053.624195636716;25711.871440642677 08:56:27 INFO - PROCESS | 1659 | |2;jquery.html;390.8475694479152;392.1686025625674;396.342072160214;399.31916083078346;415.65708290660194;19958.201671933122;20709.618393884706;21286.616369935964;21288.254911078115;21295.361139165812;20030.799692003075;20118.893460859646;20592.794072059274;20612.69080963783;20614.587708245825;5196.064708352505;5546.944530554683;5552.028395030874;5564.554835613161;5568.276124103881;5458.044842152627;5508.779648814063;5551.528120109796;5554.555635549166;5567.972160139194;5498.257735205756;5588.91616625751;5594.468525490092;5606.775728970836;5636.830895073154;5569.582281328917;5619.241402410684;5624.100143976964;5651.604387692883;5662.8301150965535;5262.973685131588;5437.619366644337;5479.4520547945285;5480.3971563128025;5492.093804522249;13494;13794.586162415144;13836.930815345859;13873.58379248624;13887.305634718223;1956.5597740508415;1958.0209895052474;2225.2100504320897;2242.1479837661745;2264.6942662740485;9278.211352035085;9846.852297215548;10091;10906.5092070857;15560.22198890051;1659.601695593049;1785.616092540097;1817.963640727178;1827.826356496144;1832.0748022248679;115682.42158789153;116085;116258.41870790592;116995;118976;11771.941140294244;11772.411379430994;11822.881771182178;11855.881441185651;11897.405129743478;15274.618134546503;15296.847031529765;15361.078335299917;15375.308111135026;15393.23033848303;7998.400119990907;8106.63520141595;8769.42998705095;8901.688440904667;8990.325725570608;7283.927160728324;8710.60802263887;8726.738197854074;8757.97849741838;8888.200061994452;4088.8977775555254;4483.103379324083;4566.70316429431;4689.812407503729;4780.3746726112595;1919.395390452003;1966.2165528925266;1970.6649869522216;1978.920843166286;1984.5270776141751;824.9463784853965;827.8799574061848;854.5001174313096;909.3134683314056;979.6921110317805;19943.800561994485;20740.170393184406;20843.791562084185;21679.566408671737;21953.780462195493;703.6974101136458;989.5744829723144;995.4674249276602;1022.0239671114217;1030.5156576409076;10651.893481065244;10956.28784129029;10993.780124397626;11005.61480348184;11013.559457621766;463106;465860;466121;619779;640416;144402;150174.49825501605;150797.99999999782;153458;155416.66874996884;1027.6660085472251;1030.907218350367;1048.905598496139;1102.685734565661;1103.2442776697987;18049.819501805337;18108.366207182684;18202.45279151287;18211.089445527406;18301.908490457463;54928.72535637456;56534;57233;57740.4225957735;58079.83840323254;76326;81797.59101204456;82191.17808821835;84074.15925840664;85428.43761521838;7720.382369410321;7900.6049697513745;8012.399070069885;8013.3188678960605;8043.316318113026;8844.601992910206;8992.145746154105;12264.509419623471;12620.936895315464;12703.80944285855;10775;10852.240343175901;11246.100311975186;11247.325160490396;11297.322160670386;10530;10753.731156721145;10855.968682975104;11020;11424.771504570028;11124.499397526968;11128.833067504156;11616.825747613962;11626.476808543805;11683.240589361665;7059.5764254144915;8181.79545511367;8250.38122140854;8364.87452688198;8595.011573669108;9487.478188699688;10985.560577576807;11026.228164028442;11245.493952772309;11399.20205585601 08:56:27 INFO - PROCESS | 1659 | |3;mootools.html;1341.7584834729744;1564.116274305018;1590.165163289273;1812.854971602272;1963.047921757947;8000.239977202164;8722.215000649949;8958.686445974392;8992.460452372863;9142.131497507742;8666.73999780006;8900.688475903346;8912.465252084863;8915.242204412632;8938.597763100659;1821.280594165304;2245.741739699939;2251.8423710340285;2428.611422172453;2464.8028157747426;2253.357793028987;2297.4026753044163;2440.6339049142593;2504.749525047499;2519.105717470299;2430.927072187837;2431.7689819467123;2441.3652450362943;2442.621393683973;2446.657467954488;2471.9011239550396;2484.3416494628937;2487.5522405966917;2488.713797913245;2490.2529241227558;2192.3422973108163;2422.4428381472185;2489.464765075499;2491.451880586268;2513.421912960011;8955.910440895637;9024.23294020009;9035.909640903572;9042.773930651787;9043.457392556467;5420.214068959979;5612.270404847384;5612.69130197839;5621.7751289948355;5622.8313150605545;3551.6803487685975;3624.4563315502623;3731.738778285521;3741.9625803741887;3812.7712337259854;1001.324106228293;1031.2626472072504;1035.3580779916424;1041.4896700616719;1045.2892033417272;12184.147109702324;12226.633201003984;12230.755384892253;12231.633051008484;12234.08244381666;4892.902141957212;4893.975530122398;5020.096382651085;5021.14640511114;5037.143685573388;8781.121887811169;10361.689149325532;10380.221483388625;10382.961703829707;10390;5551.8889622207325;5559.11054231321;5571.777128914879;5579.972100139555;5582.051051321285;4560.361549383089;4904.411470623477;4963.875903102379;5086.593072554187;5133.717645529457;3174.904752857418;4079.7964600442942;4107.199096176277;4216.536181020086;4220.85227017053;1335.7662409078373;1362.346073884541;1362.414161910388;1375.0855680972043;1375.6629625741757;771.4060173666358;908.8636704494246;914.8444764390069;951.619352259088;954.9761255968517;5287.841364759063;5473.370562385297;6029.969850150809;6112.205413296243;6196.783112591038;933.118203297877;945.7210123013728;973.4743238651049;988.8467287570438;992.1071036067597;4017.9754162688328;4053.108316170438;4061.9796901015898;4159.625633692982;4159.875203743893;9270.582823772811;11051.557937682403;11115.444227788577;11118.833217501744;11169.553217871357;9206.447613143233;10963.56145754177;11078.002979731862;11082.168837337233;11096.223264371416;7958.602069896482;8332.04181519133;8339.24946754795;8381.748547543584;8478.448900821428;6063.787767428119;6080.726367313393;6201.379862013762;6211.534134939988;6256.530760192913;9910.860251071219;10212.540435680561;10475.004874536908;10492;10511.001454861776;8926.598303076507;10260.179185665278;10658;10674;10684.57261709523;7079.787606371817;7286.781396558112;7779.4554381192775;7918.564478953713;7926.603669816602;6788.981652752127;7524.548527088174;8476.533790641573;8611.181937715906;8658.740237792876;7341.963290183515;7611.695532178873;7695.961520192363;7730.265624765639;7797.7660670179985;7568.508046976929;7578.242175782378;7611;7690.961545192238;7768.300852923264;6711.865762684816;7032.542884712478;7057.717691292496;7136.6788494518905;7138.85722285541;4896.804127834846;4957.925631115464;4984.900301994012;5067.46791586878;5205.089109405763;4238.385434112098;4777.593904518155;4965.45380008206;5141.460146684544;5308.761105750328 08:56:27 INFO - PROCESS | 1659 | |4;prototype.html;311.78798417076376;313.7411635400794;327.6952434236159;332.75708732625196;334.97822641528296;4406.71356361837;4646.163690535702;4910.680805747633;5300.787968481261;5402.351717793866;4609.285560738091;5211.400688920775;5226.928479661669;5258.318257688577;5260.105782017056;4088.6115818997146;4098.385242213677;4110.321796903508;4124.968757810548;4130.277201489742;3917.9020524486973;3998.5401678806875;4084.387341898724;4148.896277593054;4150.273702102135;3372.342393233308;3779.7354185207046;3954.051027753333;4042.3532234842432;4106.733062350952;3342.7827191232736;3558.6619271169197;3967.7303262955866;4062.939055914164;4129.793510324502;3148.480500717379;3291.637919828817;3548.769329993542;3563.768355056913;3582.6059133495296;4482.865514034552;4789.616830653539;5137.379936232548;5147.356580427447;5211.530962213381;4183.874483765492;4353.695241333108;4357.694961352707;4416.514183439819;4654.371659825914;5349;5619.297587801525;5624.3250809902665;5634.971825140848;5637.774489020434;3856.2865869814173;3857.633524815138;3861.613838616144;3889.7860617666015;3890.8716472223305;5064.468230835746;5391.487808658171;5739.368669446358;5902.527797776168;5987.922174008633;3417.8120203388553;3848.9037774055873;3872.1094148345474;3910.276598829226;3921.450996860442;3968.7420317679257;3999.5200575931076;4001.919961600752;4011.2579172852534;4011.338129208706;3677.191017976041;4370.322599997005;4444.955550444519;4663.883402914954;4671.8364857229835;5279.970405770838;5705.5720820938595;5722.599418040781;5730.627509211888;5748.48263656273;1270.7585558743808;1322.543722415765;1352.3373546961918;1361.1220762608061;1364.3655700099303;706.7173130747742;799.2566912771179;835.1731785532401;843.5191940593803;849.2781136034322;4061.0557345923967;4239.27932251518;4321.503027151855;4439.467263928349;4469.128519938646;4620.792064357112;4880.853574392774;4921.5303211236;4926.423544465737;4930.778114984834;4132.876013719593;4334.43652325202;4417.249067658506;4439.267520859086;4467.369410165331;2368.325027367192;2593.727658595858;2613.294410509152;2615.9869200654257;2616.3328351270306;4257.1911336846515;4403.823847046082;4562.954370456319;4964.90070198594;5082.974585127051;4457.531959144308;4905.241915342112;5016.413264515889;5029.497050294941;5124.871878203075;4627.5835174833755;4715.830717869819;5061.721605311744;5084.584822209421;5116.974415127975;4412.183746006999;4418.447694038246;4555.9452087642685;4837.4920633334405;4849.127157111754;4566.840160594364;4601.504511033994;4845.8061677532505;4846.781894814671;4896.6572339935865;4455.465344158722;4540.886477837948;4725.464224127241;5108.182690769459;5189.714565698923;2971.7815695564714;3086.2438702518025;3231.6929891660257;3407.7444191685227;3434.9484757729156;4126.286342291961;4901.509849015212;4906.375089327193;4953.7523123844385;4995.400551933792;3831.482749828849;3943;4188.539260681262;4446.533114022981;4718.891060600825;4018.693924474499;4097.009183296151;4298.097399546005;4349.846825821112;4508.797104130387;4136.689748268952;4144.129732756155;4385.780710964503;4403.524819185609;4707.882302942454;3756.708407871251;4139.441175441277;4406.008648054162;4490.977545112253;4785.569298762989;3846.9038274043373;4460.9107817844115;4604.792784324646;4607.14767767964;4626.409210841939 08:56:27 INFO - PROCESS | 1659 | |5;yui.html;643.9356064393561;686.1628812848325;707.2856415020827;710.754789597589;717.0713925466521;15001.39994400224;15376.308066137022;15572.688546229068;15643.530694079196;15649.921750391246;14099;14104.435822567111;14132.434702611883;14151.504697335595;14178.71642567148;678.7420780103554;694.8783962806514;850.408965768791;850.506706110456;875.1336177184605;855.9315254779634;858.3133493205441;865.7748985263813;868.3010176807699;869.3306154261215;926.8795056642628;933.3280038372361;934.2992755433424;934.6121359635777;935.6584846531001;711.7117567385217;892.0900681305033;916.120524296676;918.4994178172911;926.4950601921935;687.99552653126;688.3288793426419;689.1798759476208;690.6408667492875;691.3225039461356;14954;14993.025453345497;15020.17389043602;15027.94804363695;15029.699406011929;12365;12406.317652529107;12433.875661243363;12497.062720295924;12498.875011249862;10859.83710244347;10982.176336774774;11009.779804403946;11433.885661143366;11581.42092895352;4111.8560850370395;4130.938035929464;4176.895577610554;4237.470316210473;4272.722273052273;83869;84308;86855.74870387303;86859.99999999937;86971.56514217451;9403.529823508798;9576.808463830685;9655;9791.706248812547;9817.509124543743;22187.445313867283;22206.444838879157;22209.777902221093;22229;22301.442463938532;6192.783252586138;7318;7351.228121047213;7360.33756961876;7367.852642947111;3964.7621142731527;3997.060690737674;4036.7981600920425;4040.4545386372465;4047.332190188586;2806.789490788199;3529.7882127072457;3539.0975301298004;3544.2379888324135;3546;618.9907151392732;629.5309994054459;631.0691729698749;651.5829868883918;653.0628548033587;511.64185070450833;529.9973500132475;545.664416383921;556.6993823335354;570.6433479075578;7638.618069096634;7698.692052317957;7815.570143642154;7927.920720792834;7956.721514746956;551.834449665107;568.5366426362584;569.9031164702011;571.7770069672831;582.7785441532276;3571.785692858437;3716.1452865841006;3781.829817658157;3808.6000969898314;3961.207758448264;73800;94735.52632236795;95859;96121;97714.51142744241;82656.17343826605;83413.74879376814;84092.57953710192;84445.57777211074;87867.1213287859;52163.73918130461;66529.6694066116;76268.23731762722;77036;77973.08692116133;6324.367563243639;6855.245922948372;7223.853019624146;7261.52800068015;7353.301436363537;38283;44024.11951761011;44966.20561529599;45157.96789144431;46534.53465465302;52531.47468525266;60374.69812650909;61202.081968771396;63971.72056558749;66768;9714.562844672148;10038.29731918788;10080.899191007997;10158.18734501253;10341.276110672481;10508.417105473101;10542.8418573723;10596.576136954436;10682.359058456517;10692.732681683021;9790.80418391614;9863.161631261422;9926.404415735078;10108.140808031105;10359.22305827081;9789.412635241908;10097.545610447694;10187.337823041478;10369.274150809371;11000.394978276272;8073.3541316693345;8335.333173346238;10054.54754536033;10070.69787906364;10739.979701928305;8341.791455213426;8360.038595561587;8487.321014318963;8687.522186279815;8749.562521873753;7531.623418829146;8109.59452027408;8538.573071346284;8542.40203185771;8569.185927336894 08:56:27 INFO - PROCESS | 1659 | __end_tp_report 08:56:27 INFO - PROCESS | 1659 | __start_cc_report 08:56:27 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1287 08:56:27 INFO - PROCESS | 1659 | __end_cc_report 08:56:27 INFO - PROCESS | 1659 | __startTimestamp1463673387266__endTimestamp 08:56:27 INFO - PROCESS | 1659 | 08:56:27 INFO - PROCESS | 1659 | ------- Summary: start ------- 08:56:27 INFO - PROCESS | 1659 | Number of tests: 6 08:56:27 INFO - PROCESS | 1659 | 08:56:27 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5780.59 Median:6064.15 stddev:670.24 (11.1%) stddev-sans-first:634.18 08:56:27 INFO - PROCESS | 1659 | Values: 2940.4 3435.2 3462.5 3464.7 3511.6 5621.7 5647.0 5657.4 5735.4 5764.8 5718.6 5729.5 5785.9 5788.8 5869.0 5149.9 5219.4 5254.8 5286.5 5366.2 5335.6 5361.7 5374.3 5386.2 5398.0 5151.8 5190.7 5208.9 5227.4 5234.5 5195.4 5218.2 5233.6 5253.0 5264.8 4655.7 5058.2 5237.8 5271.4 5293.3 5549.6 5597.6 5662.2 5668.5 5750.8 6323.0 6330.6 6334.0 6347.9 6348.9 6306.8 6369.4 6379.8 6388.9 6392.2 5716.4 5738.8 5803.5 5818.8 5919.9 5814.2 6182.4 6290.4 6329.1 6342.8 6178.5 6181.7 6188.9 6191.2 6192.8 6109.9 6205.3 6220.3 6224.9 6311.1 6159.4 6210.3 6217.1 6226.8 6240.0 6253.1 6253.6 6270.1 6281.9 6299.9 4872.1 4886.2 4890.2 4891.1 4951.4 3768.4 4478.2 4490.2 4492.1 4502.8 5621.2 5622.3 5622.9 5624.3 5634.4 6251.0 6257.2 6261.6 6262.3 6342.7 5653.6 5662.2 5675.6 5680.8 5692.6 5349.8 5419.9 5428.9 5448.9 5455.5 6276.1 6291.2 6332.1 6395.9 6398.2 6376.6 6384.6 6388.7 6391.2 6403.4 6281.1 6298.5 6409.7 6409.9 6416.4 6150.7 6293.4 6307.1 6323.2 6334.8 6333.7 6337.2 6392.8 6396.8 6418.7 6033.2 6105.0 6111.7 6187.9 6203.3 6220.7 6229.9 6230.1 6232.8 6261.3 5995.2 6050.8 6064.2 6065.1 6114.0 5899.9 6047.1 6097.4 6106.9 6134.9 6056.4 6064.7 6074.4 6082.9 6112.1 08:56:27 INFO - PROCESS | 1659 | 08:56:27 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:69314.42 Median:38074.21 stddev:151859.64 (398.9%) stddev-sans-first:152197.75 08:56:27 INFO - PROCESS | 1659 | Values: 498.0 509.2 510.6 521.6 524.9 31312.5 31902.5 43503.8 44305.1 44359.8 42724.8 45426.8 45488.3 45488.5 45516.8 9812.4 10010.3 10013.2 10013.6 10014.2 8506.1 9712.4 9861.0 9870.2 9889.3 9821.1 9885.0 9891.8 9912.3 9914.8 9820.7 9876.0 9886.2 9886.8 9895.4 9748.6 9837.4 9859.2 9864.2 9867.0 34117.5 41509.0 42352.4 42445.8 42465.8 3179.7 3297.6 3330.2 3352.6 3480.5 29629.9 29933.6 30057.1 30443.0 30587.7 2739.1 2753.4 2770.3 2848.9 2902.5 521976.4 522301.0 524130.0 524939.0 525635.0 63697.0 63941.0 64433.7 64555.2 64652.0 36573.5 37385.1 37511.6 38616.0 38664.8 14299.6 17375.7 17414.5 17420.3 17427.0 13081.1 13145.4 13176.8 13180.9 13190.7 17259.0 19967.4 20179.8 20385.1 20574.2 6478.7 6496.5 7647.4 7654.6 7707.1 909.4 953.6 1036.0 1097.4 1112.1 28652.0 29498.9 29551.7 30309.4 30437.6 826.9 961.3 1081.6 1100.7 1125.5 15215.7 15256.5 15387.0 15546.6 15599.6 653157.0 662241.0 665353.0 667934.0 668159.0 61575.7 62085.0 62168.1 62223.4 62262.0 1307.1 1441.3 1450.7 1474.8 1487.4 22410.0 29602.9 30407.4 30628.9 31348.8 52403.2 52521.0 53280.0 53293.7 53327.0 502109.0 503039.0 503435.0 504159.5 505052.0 23498.9 26305.7 26982.5 27123.2 27369.7 46467.0 46784.1 47044.0 47522.5 48203.8 35223.6 35829.1 36410.6 38185.6 38694.0 37001.8 37398.3 37819.0 37920.6 37962.8 32221.8 37845.6 39233.6 39285.2 40889.6 26925.6 32914.0 33963.5 33965.3 34157.8 23110.0 24157.8 24885.0 25053.6 25711.9 08:56:27 INFO - PROCESS | 1659 | 08:56:27 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:33634.23 Median:21291.81 stddev:91202.06 (428.3%) stddev-sans-first:91423.75 08:56:27 INFO - PROCESS | 1659 | Values: 390.8 392.2 396.3 399.3 415.7 19958.2 20709.6 21286.6 21288.3 21295.4 20030.8 20118.9 20592.8 20612.7 20614.6 5196.1 5546.9 5552.0 5564.6 5568.3 5458.0 5508.8 5551.5 5554.6 5568.0 5498.3 5588.9 5594.5 5606.8 5636.8 5569.6 5619.2 5624.1 5651.6 5662.8 5263.0 5437.6 5479.5 5480.4 5492.1 13494.0 13794.6 13836.9 13873.6 13887.3 1956.6 1958.0 2225.2 2242.1 2264.7 9278.2 9846.9 10091.0 10906.5 15560.2 1659.6 1785.6 1818.0 1827.8 1832.1 115682.4 116085.0 116258.4 116995.0 118976.0 11771.9 11772.4 11822.9 11855.9 11897.4 15274.6 15296.8 15361.1 15375.3 15393.2 7998.4 8106.6 8769.4 8901.7 8990.3 7283.9 8710.6 8726.7 8758.0 8888.2 4088.9 4483.1 4566.7 4689.8 4780.4 1919.4 1966.2 1970.7 1978.9 1984.5 824.9 827.9 854.5 909.3 979.7 19943.8 20740.2 20843.8 21679.6 21953.8 703.7 989.6 995.5 1022.0 1030.5 10651.9 10956.3 10993.8 11005.6 11013.6 463106.0 465860.0 466121.0 619779.0 640416.0 144402.0 150174.5 150798.0 153458.0 155416.7 1027.7 1030.9 1048.9 1102.7 1103.2 18049.8 18108.4 18202.5 18211.1 18301.9 54928.7 56534.0 57233.0 57740.4 58079.8 76326.0 81797.6 82191.2 84074.2 85428.4 7720.4 7900.6 8012.4 8013.3 8043.3 8844.6 8992.1 12264.5 12620.9 12703.8 10775.0 10852.2 11246.1 11247.3 11297.3 10530.0 10753.7 10856.0 11020.0 11424.8 11124.5 11128.8 11616.8 11626.5 11683.2 7059.6 8181.8 8250.4 8364.9 8595.0 9487.5 10985.6 11026.2 11245.5 11399.2 08:56:27 INFO - PROCESS | 1659 | 08:56:27 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5825.40 Median:5002.50 stddev:3263.69 (65.2%) stddev-sans-first:3255.45 08:56:27 INFO - PROCESS | 1659 | Values: 1341.8 1564.1 1590.2 1812.9 1963.0 8000.2 8722.2 8958.7 8992.5 9142.1 8666.7 8900.7 8912.5 8915.2 8938.6 1821.3 2245.7 2251.8 2428.6 2464.8 2253.4 2297.4 2440.6 2504.7 2519.1 2430.9 2431.8 2441.4 2442.6 2446.7 2471.9 2484.3 2487.6 2488.7 2490.3 2192.3 2422.4 2489.5 2491.5 2513.4 8955.9 9024.2 9035.9 9042.8 9043.5 5420.2 5612.3 5612.7 5621.8 5622.8 3551.7 3624.5 3731.7 3742.0 3812.8 1001.3 1031.3 1035.4 1041.5 1045.3 12184.1 12226.6 12230.8 12231.6 12234.1 4892.9 4894.0 5020.1 5021.1 5037.1 8781.1 10361.7 10380.2 10383.0 10390.0 5551.9 5559.1 5571.8 5580.0 5582.1 4560.4 4904.4 4963.9 5086.6 5133.7 3174.9 4079.8 4107.2 4216.5 4220.9 1335.8 1362.3 1362.4 1375.1 1375.7 771.4 908.9 914.8 951.6 955.0 5287.8 5473.4 6030.0 6112.2 6196.8 933.1 945.7 973.5 988.8 992.1 4018.0 4053.1 4062.0 4159.6 4159.9 9270.6 11051.6 11115.4 11118.8 11169.6 9206.4 10963.6 11078.0 11082.2 11096.2 7958.6 8332.0 8339.2 8381.7 8478.4 6063.8 6080.7 6201.4 6211.5 6256.5 9910.9 10212.5 10475.0 10492.0 10511.0 8926.6 10260.2 10658.0 10674.0 10684.6 7079.8 7286.8 7779.5 7918.6 7926.6 6789.0 7524.5 8476.5 8611.2 8658.7 7342.0 7611.7 7696.0 7730.3 7797.8 7568.5 7578.2 7611.0 7691.0 7768.3 6711.9 7032.5 7057.7 7136.7 7138.9 4896.8 4957.9 4984.9 5067.5 5205.1 4238.4 4777.6 4965.5 5141.5 5308.8 08:56:27 INFO - PROCESS | 1659 | 08:56:27 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4119.31 Median:4416.88 stddev:1205.91 (27.3%) stddev-sans-first:1174.94 08:56:27 INFO - PROCESS | 1659 | Values: 311.8 313.7 327.7 332.8 335.0 4406.7 4646.2 4910.7 5300.8 5402.4 4609.3 5211.4 5226.9 5258.3 5260.1 4088.6 4098.4 4110.3 4125.0 4130.3 3917.9 3998.5 4084.4 4148.9 4150.3 3372.3 3779.7 3954.1 4042.4 4106.7 3342.8 3558.7 3967.7 4062.9 4129.8 3148.5 3291.6 3548.8 3563.8 3582.6 4482.9 4789.6 5137.4 5147.4 5211.5 4183.9 4353.7 4357.7 4416.5 4654.4 5349.0 5619.3 5624.3 5635.0 5637.8 3856.3 3857.6 3861.6 3889.8 3890.9 5064.5 5391.5 5739.4 5902.5 5987.9 3417.8 3848.9 3872.1 3910.3 3921.5 3968.7 3999.5 4001.9 4011.3 4011.3 3677.2 4370.3 4445.0 4663.9 4671.8 5280.0 5705.6 5722.6 5730.6 5748.5 1270.8 1322.5 1352.3 1361.1 1364.4 706.7 799.3 835.2 843.5 849.3 4061.1 4239.3 4321.5 4439.5 4469.1 4620.8 4880.9 4921.5 4926.4 4930.8 4132.9 4334.4 4417.2 4439.3 4467.4 2368.3 2593.7 2613.3 2616.0 2616.3 4257.2 4403.8 4563.0 4964.9 5083.0 4457.5 4905.2 5016.4 5029.5 5124.9 4627.6 4715.8 5061.7 5084.6 5117.0 4412.2 4418.4 4555.9 4837.5 4849.1 4566.8 4601.5 4845.8 4846.8 4896.7 4455.5 4540.9 4725.5 5108.2 5189.7 2971.8 3086.2 3231.7 3407.7 3434.9 4126.3 4901.5 4906.4 4953.8 4995.4 3831.5 3943.0 4188.5 4446.5 4718.9 4018.7 4097.0 4298.1 4349.8 4508.8 4136.7 4144.1 4385.8 4403.5 4707.9 3756.7 4139.4 4406.0 4491.0 4785.6 3846.9 4460.9 4604.8 4607.1 4626.4 08:56:27 INFO - PROCESS | 1659 | 08:56:27 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:17995.54 Median:3477.72 stddev:26102.71 (750.6%) stddev-sans-first:26143.42 08:56:27 INFO - PROCESS | 1659 | Values: 643.9 686.2 707.3 710.8 717.1 15001.4 15376.3 15572.7 15643.5 15649.9 14099.0 14104.4 14132.4 14151.5 14178.7 678.7 694.9 850.4 850.5 875.1 855.9 858.3 865.8 868.3 869.3 926.9 933.3 934.3 934.6 935.7 711.7 892.1 916.1 918.5 926.5 688.0 688.3 689.2 690.6 691.3 14954.0 14993.0 15020.2 15027.9 15029.7 12365.0 12406.3 12433.9 12497.1 12498.9 10859.8 10982.2 11009.8 11433.9 11581.4 4111.9 4130.9 4176.9 4237.5 4272.7 83869.0 84308.0 86855.7 86860.0 86971.6 9403.5 9576.8 9655.0 9791.7 9817.5 22187.4 22206.4 22209.8 22229.0 22301.4 6192.8 7318.0 7351.2 7360.3 7367.9 3964.8 3997.1 4036.8 4040.5 4047.3 2806.8 3529.8 3539.1 3544.2 3546.0 619.0 629.5 631.1 651.6 653.1 511.6 530.0 545.7 556.7 570.6 7638.6 7698.7 7815.6 7927.9 7956.7 551.8 568.5 569.9 571.8 582.8 3571.8 3716.1 3781.8 3808.6 3961.2 73800.0 94735.5 95859.0 96121.0 97714.5 82656.2 83413.7 84092.6 84445.6 87867.1 52163.7 66529.7 76268.2 77036.0 77973.1 6324.4 6855.2 7223.9 7261.5 7353.3 38283.0 44024.1 44966.2 45158.0 46534.5 52531.5 60374.7 61202.1 63971.7 66768.0 9714.6 10038.3 10080.9 10158.2 10341.3 10508.4 10542.8 10596.6 10682.4 10692.7 9790.8 9863.2 9926.4 10108.1 10359.2 9789.4 10097.5 10187.3 10369.3 11000.4 8073.4 8335.3 10054.5 10070.7 10740.0 8341.8 8360.0 8487.3 8687.5 8749.6 7531.6 8109.6 8538.6 8542.4 8569.2 08:56:27 INFO - PROCESS | 1659 | -------- Summary: end -------- 08:56:27 INFO - PROCESS | 1659 | 08:56:27 INFO - TEST-INFO | 1659: exit 0 08:56:27 INFO - TEST-OK | dromaeo_css | took 1114594ms 08:56:27 INFO - TEST-START | kraken 08:56:27 INFO - operating with platform_type : mac_ 08:56:27 INFO - Initialising browser for kraken test... 08:56:27 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpYuq6vt/profile http://localhost:49285/getInfo.html) 08:56:30 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 08:56:30 INFO - PROCESS | 1669 | colorDepth:24 08:56:30 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 08:56:30 INFO - PROCESS | 1669 | __metrics 08:56:30 INFO - PROCESS | 1669 | 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" 08:56:30 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:56:30 INFO - TEST-INFO | 1669: exit 0 08:56:30 INFO - Browser initialized. 08:56:30 INFO - Running cycle 1/1 for kraken test... 08:56:30 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpYuq6vt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:56:42 INFO - PROCESS | 1671 | RSS: Main: 238813184 08:56:42 INFO - PROCESS | 1671 | 08:56:45 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49285/tests/kraken/driver.html?testName=audio-beat-detection) 08:56:46 INFO - PROCESS | 1671 | RSS: Main: 286666752 08:56:46 INFO - PROCESS | 1671 | 08:56:49 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49285/tests/kraken/driver.html?testName=audio-dft) 08:56:49 INFO - PROCESS | 1671 | RSS: Main: 295895040 08:56:49 INFO - PROCESS | 1671 | 08:56:53 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49285/tests/kraken/driver.html?testName=audio-fft) 08:56:54 INFO - PROCESS | 1671 | RSS: Main: 385597440 08:56:54 INFO - PROCESS | 1671 | 08:56:56 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49285/tests/kraken/driver.html?testName=audio-oscillator) 08:56:56 INFO - PROCESS | 1671 | RSS: Main: 283222016 08:56:56 INFO - PROCESS | 1671 | 08:56:59 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49285/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:56:59 INFO - PROCESS | 1671 | RSS: Main: 286711808 08:56:59 INFO - PROCESS | 1671 | 08:57:02 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49285/tests/kraken/driver.html?testName=imaging-darkroom) 08:57:03 INFO - PROCESS | 1671 | RSS: Main: 325558272 08:57:03 INFO - PROCESS | 1671 | 08:57:06 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49285/tests/kraken/driver.html?testName=imaging-desaturate) 08:57:06 INFO - PROCESS | 1671 | RSS: Main: 329080832 08:57:06 INFO - PROCESS | 1671 | 08:57:10 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49285/tests/kraken/driver.html?testName=json-parse-financial) 08:57:10 INFO - PROCESS | 1671 | RSS: Main: 328425472 08:57:10 INFO - PROCESS | 1671 | 08:57:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49285/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:57:13 INFO - PROCESS | 1671 | RSS: Main: 316473344 08:57:13 INFO - PROCESS | 1671 | 08:57:15 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:57:15 INFO - PROCESS | 1671 | RSS: Main: 323211264 08:57:15 INFO - PROCESS | 1671 | 08:57:17 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:57:18 INFO - PROCESS | 1671 | RSS: Main: 324833280 08:57:18 INFO - PROCESS | 1671 | 08:57:21 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:57:22 INFO - PROCESS | 1671 | RSS: Main: 329744384 08:57:22 INFO - PROCESS | 1671 | 08:57:25 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:57:25 INFO - PROCESS | 1671 | RSS: Main: 327442432 08:57:25 INFO - PROCESS | 1671 | 08:57:27 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49285/tests/kraken/driver.html?testName=ai-astar) 08:57:27 INFO - PROCESS | 1671 | RSS: Main: 374284288 08:57:27 INFO - PROCESS | 1671 | 08:57:27 INFO - PROCESS | 1671 | __start_tp_report 08:57:27 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 08:57:27 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 08:57:27 INFO - PROCESS | 1671 | |i|pagename|runs| 08:57:27 INFO - PROCESS | 1671 | |0;ai-astar;112;105;112;114;157;166;112;111;112;111 08:57:27 INFO - PROCESS | 1671 | |1;audio-beat-detection;155;121;142;197;116;140;149;126;209;117 08:57:27 INFO - PROCESS | 1671 | |2;audio-dft;187;184;183;185;181;184;183;186;182;185 08:57:27 INFO - PROCESS | 1671 | |3;audio-fft;78;73;57;125;57;61;56;59;110;56 08:57:27 INFO - PROCESS | 1671 | |4;audio-oscillator;91;137;152;79;79;76;101;78;80;100 08:57:27 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;88;85;89;85;88;85;88;86;88;85 08:57:27 INFO - PROCESS | 1671 | |6;imaging-darkroom;94;93;93;95;93;94;94;94;94;94 08:57:27 INFO - PROCESS | 1671 | |7;imaging-desaturate;126;134;114;87;84;84;84;99;140;133 08:57:27 INFO - PROCESS | 1671 | |8;json-parse-financial;56;59;58;58;58;58;57;118;57;56 08:57:27 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;64;67;72;73;70;68;67;69;67;68 08:57:27 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;85;82;85;85;84;82;85;88;102;86 08:57:27 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;137;136;136;135;141;139;141;135;208;133 08:57:27 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;162;162;160;158;163;165;159;161;164;161 08:57:27 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;58;82;84;66;65;65;64;66;64;64 08:57:27 INFO - PROCESS | 1671 | __end_tp_report 08:57:27 INFO - PROCESS | 1671 | __start_cc_report 08:57:27 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,2211 08:57:27 INFO - PROCESS | 1671 | __end_cc_report 08:57:27 INFO - PROCESS | 1671 | __startTimestamp1463673447692__endTimestamp 08:57:27 INFO - PROCESS | 1671 | 08:57:27 INFO - PROCESS | 1671 | ------- Summary: start ------- 08:57:27 INFO - PROCESS | 1671 | Number of tests: 14 08:57:27 INFO - PROCESS | 1671 | 08:57:27 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:121.20 Median:112.00 stddev:21.47 (19.2%) stddev-sans-first:22.52 08:57:27 INFO - PROCESS | 1671 | Values: 112.0 105.0 112.0 114.0 157.0 166.0 112.0 111.0 112.0 111.0 08:57:27 INFO - PROCESS | 1671 | 08:57:27 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:147.20 Median:145.50 stddev:32.43 (22.3%) stddev-sans-first:34.27 08:57:27 INFO - PROCESS | 1671 | Values: 155.0 121.0 142.0 197.0 116.0 140.0 149.0 126.0 209.0 117.0 08:57:27 INFO - PROCESS | 1671 | 08:57:27 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.50 stddev:1.83 (1.0%) stddev-sans-first:1.58 08:57:27 INFO - PROCESS | 1671 | Values: 187.0 184.0 183.0 185.0 181.0 184.0 183.0 186.0 182.0 185.0 08:57:27 INFO - PROCESS | 1671 | 08:57:27 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:73.20 Median:58.00 stddev:24.78 (42.7%) stddev-sans-first:26.22 08:57:27 INFO - PROCESS | 1671 | Values: 78.0 73.0 57.0 125.0 57.0 61.0 56.0 59.0 110.0 56.0 08:57:27 INFO - PROCESS | 1671 | 08:57:27 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:97.30 Median:78.50 stddev:26.72 (34.0%) stddev-sans-first:28.24 08:57:27 INFO - PROCESS | 1671 | Values: 91.0 137.0 152.0 79.0 79.0 76.0 101.0 78.0 80.0 100.0 08:57:27 INFO - PROCESS | 1671 | 08:57:27 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:88.00 stddev:1.64 (1.9%) stddev-sans-first:1.67 08:57:27 INFO - PROCESS | 1671 | Values: 88.0 85.0 89.0 85.0 88.0 85.0 88.0 86.0 88.0 85.0 08:57:27 INFO - PROCESS | 1671 | 08:57:27 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 08:57:27 INFO - PROCESS | 1671 | Values: 94.0 93.0 93.0 95.0 93.0 94.0 94.0 94.0 94.0 94.0 08:57:27 INFO - PROCESS | 1671 | 08:57:27 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:108.50 Median:84.00 stddev:23.41 (27.9%) stddev-sans-first:23.96 08:57:27 INFO - PROCESS | 1671 | Values: 126.0 134.0 114.0 87.0 84.0 84.0 84.0 99.0 140.0 133.0 08:57:27 INFO - PROCESS | 1671 | 08:57:27 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:58.00 stddev:19.17 (33.1%) stddev-sans-first:20.14 08:57:27 INFO - PROCESS | 1671 | Values: 56.0 59.0 58.0 58.0 58.0 58.0 57.0 118.0 57.0 56.0 08:57:27 INFO - PROCESS | 1671 | 08:57:27 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:68.50 Median:68.50 stddev:2.64 (3.8%) stddev-sans-first:2.24 08:57:27 INFO - PROCESS | 1671 | Values: 64.0 67.0 72.0 73.0 70.0 68.0 67.0 69.0 67.0 68.0 08:57:27 INFO - PROCESS | 1671 | 08:57:27 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:85.00 stddev:5.76 (6.8%) stddev-sans-first:6.09 08:57:27 INFO - PROCESS | 1671 | Values: 85.0 82.0 85.0 85.0 84.0 82.0 85.0 88.0 102.0 86.0 08:57:27 INFO - PROCESS | 1671 | 08:57:27 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:144.10 Median:138.00 stddev:22.61 (16.4%) stddev-sans-first:23.83 08:57:27 INFO - PROCESS | 1671 | Values: 137.0 136.0 136.0 135.0 141.0 139.0 141.0 135.0 208.0 133.0 08:57:27 INFO - PROCESS | 1671 | 08:57:27 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.50 Median:162.00 stddev:2.17 (1.3%) stddev-sans-first:2.30 08:57:27 INFO - PROCESS | 1671 | Values: 162.0 162.0 160.0 158.0 163.0 165.0 159.0 161.0 164.0 161.0 08:57:27 INFO - PROCESS | 1671 | 08:57:27 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:65.50 stddev:8.34 (12.7%) stddev-sans-first:8.05 08:57:27 INFO - PROCESS | 1671 | Values: 58.0 82.0 84.0 66.0 65.0 65.0 64.0 66.0 64.0 64.0 08:57:27 INFO - PROCESS | 1671 | -------- Summary: end -------- 08:57:27 INFO - PROCESS | 1671 | 08:57:28 INFO - TEST-INFO | 1671: exit 0 08:57:28 INFO - TEST-OK | kraken | took 60654ms 08:57:28 INFO - SUITE-END | took 1175s 08:57:28 INFO - Completed test suite (00:19:35) 08:57:28 INFO - javascript benchmark 08:57:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2940.3531223130913, 3435.227073908371, 3462.5152478652985, 3464.6881780639737, 3511.5610548681416, 5621.662700237988, 5647.0, 5657.405972372903, 5735.397783232763, 5764.798232061884, 5718.59969802114, 5729.512991395736, 5785.884282314351, 5788.768449262035, 5868.970655146718, 5149.948500515003, 5219.373675158986, 5254.763535640886, 5286.497782710637, 5366.168243922199, 5335.626506144571, 5361.678299302054, 5374.328208973879, 5386.1920711893135, 5397.973010134944, 5151.76817043232, 5190.7404629768735, 5208.869778255536, 5227.424983251839, 5234.502722241381, 5195.350581177353, 5218.165093585008, 5233.633645644806, 5253.028189784864, 5264.815731449419, 4655.697379670344, 5058.190689489701, 5237.816676416307, 5271.42014378418, 5293.285406470116, 5549.639273447213, 5597.608167428281, 5662.235598194232, 5668.518175955049, 5750.827475175752, 6323.019931910561, 6330.556861019731, 6333.98656215008, 6347.936520634781, 6348.9365106348805, 6306.779262725782, 6369.426751592379, 6379.776707815251, 6388.904166437507, 6392.20097487814, 5716.428357164292, 5738.799142030009, 5803.5357171426185, 5818.8254352369495, 5919.881602367928, 5814.244148260699, 6182.381761823782, 6290.433860952537, 6329.050642403677, 6342.841428964312, 6178.505719542516, 6181.721822517998, 6188.938110618927, 6191.164192834, 6192.783252586138, 6109.890546867989, 6205.348438413992, 6220.25356957159, 6224.875502489925, 6311.053341998645, 6159.384061593805, 6210.254769427697, 6217.067439884054, 6226.750929962751, 6240.0, 6253.1245625612455, 6253.624782513064, 6270.059491076375, 6281.937180628227, 6299.874002519924, 4872.14737420957, 4886.193778026657, 4890.168671325883, 4891.070696567641, 4951.430585482643, 3768.3970564709516, 4478.216312145361, 4490.191765482179, 4492.0791237796175, 4502.752348620857, 5621.156826476062, 5622.297212848394, 5622.91565626516, 5624.29696287964, 5634.3802181759975, 6251.0, 6257.2491301044165, 6261.624302541862, 6262.342454042351, 6342.714577844008, 5653.632513886584, 5662.207290979266, 5675.574331925122, 5680.829575112753, 5692.6299790513485, 5349.81275655358, 5419.864503387368, 5428.945710542844, 5448.863778405572, 5455.536279416215, 6276.089966954766, 6291.150694656254, 6332.050192471166, 6395.936040639534, 6398.2322121344805, 6376.649284289409, 6384.585001974857, 6388.744450221939, 6391.201099862517, 6403.391677790602, 6281.12064311006, 6298.464630506458, 6409.7115629795835, 6409.9359006411205, 6416.422521973045, 6150.723217455135, 6293.37066293367, 6307.085472606538, 6323.241211054703, 6334.809955701152, 6333.714982825692, 6337.176167098248, 6392.776252831129, 6396.808095757135, 6418.679066046772, 6033.155358249936, 6105.004892149239, 6111.694415279308, 6187.938120618736, 6203.2556093269095, 6220.657863817533, 6229.937700622936, 6230.06549017651, 6232.781852635136, 6261.342559031418, 5995.2206213192, 6050.757969681164, 6064.151018857452, 6065.059915713072, 6114.0, 5899.911501327398, 6047.0929360596265, 6097.373446762037, 6106.908396374147, 6134.926457743815, 6056.394360563909, 6064.696765161812, 6074.3925607438905, 6082.878342433038, 6112.113743507255], "value": 5735.529283466167, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.03381440006393, 509.17513627922773, 510.56856955872286, 521.616611790334, 524.9475052494753, 31312.53031204531, 31902.521462178058, 43503.78248108755, 44305.113897722025, 44359.77820110903, 42724.78637606815, 45426.77286613562, 45488.317675234895, 45488.54137503496, 45516.77241613787, 9812.362196457243, 10010.34932729374, 10013.249006324519, 10013.649522266722, 10014.248931330143, 8506.149385061506, 9712.368696034771, 9860.950695246549, 9870.21038316933, 9889.307748457612, 9821.116099551038, 9884.950575247114, 9891.752706182368, 9912.306138570302, 9914.801703965952, 9820.705378838646, 9875.950620246853, 9886.209103271756, 9886.802263954753, 9895.406275623414, 9748.561314740853, 9837.409755414697, 9859.211263098934, 9864.161546268575, 9867.013298670146, 34117.48823767646, 41509.0, 42352.36471452931, 42445.78777106095, 42465.78767106176, 3179.682031796825, 3297.5878015248095, 3330.234046169395, 3352.6312105668358, 3480.5127282180515, 29629.851850740823, 29933.550996735066, 30057.098287051424, 30443.0, 30587.69412305893, 2739.055026016021, 2753.3942532642786, 2770.252031951362, 2848.9316506310133, 2902.506573882445, 521976.3901180508, 522301.0, 524130.0, 524938.9999999962, 525635.0, 63697.044544331875, 63941.0, 64433.67783161148, 64555.22170172158, 64652.0, 36573.451398229045, 37385.06537336534, 37511.624883751356, 38616.0, 38664.80667596644, 14299.64250893736, 17375.73936390955, 17414.47756567305, 17420.303187872596, 17427.0, 13081.084324097223, 13145.408456619474, 13176.802347964789, 13180.934095329461, 13190.670233244055, 17259.0, 19967.400977970683, 20179.79820201779, 20385.08267127953, 20574.224667144972, 6478.676066196671, 6496.512761542903, 7647.426443016796, 7654.578998155043, 7707.113681926538, 909.4361495872602, 953.5518306395983, 1035.984460233097, 1097.3854641400842, 1112.093643680398, 28652.0, 29498.852505737334, 29551.704482954894, 30309.393812124075, 30437.586549950134, 826.900771907375, 961.3414810854587, 1081.599808071003, 1100.6626948257906, 1125.454154734953, 15215.695686086216, 15256.466023689118, 15387.0, 15546.611334716496, 15599.610009749847, 653157.0, 662241.0, 665353.0, 667934.0, 668159.0, 61575.692121539105, 62085.0, 62168.067478987854, 62223.37776622176, 62262.0, 1307.1372893889986, 1441.3081720773878, 1450.6881020580415, 1474.8230212374583, 1487.3530014443752, 22409.991550380597, 29602.851985739933, 30407.391852163277, 30628.92798752033, 31348.843255783577, 52403.21395178999, 52521.0, 53280.0, 53293.733531332095, 53327.0, 502109.0, 503038.9696103138, 503435.0, 504159.47920260165, 505052.0, 23498.882505588048, 26305.73694263033, 26982.460350793266, 27123.186304410898, 27369.72630273672, 46467.0, 46784.06431871412, 47044.0, 47522.52477475181, 48203.75898120487, 35223.647763522036, 35829.1042723934, 36410.63589364073, 38185.6181438182, 38694.0, 37001.814990924875, 37398.252034959696, 37819.0, 37920.577905890066, 37962.810185948896, 32221.838890805397, 37845.62154378531, 39233.607663922994, 39285.2142957145, 40889.59110408858, 26925.596116057885, 32914.0, 33963.49054764131, 33965.3206935855, 34157.829210853786, 23110.0, 24157.792110394763, 24885.0, 25053.624195636716, 25711.871440642677], "value": 19729.773819309994, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.8475694479152, 392.1686025625674, 396.342072160214, 399.31916083078346, 415.65708290660194, 19958.201671933122, 20709.618393884706, 21286.616369935964, 21288.254911078115, 21295.361139165812, 20030.799692003075, 20118.893460859646, 20592.794072059274, 20612.69080963783, 20614.587708245825, 5196.064708352505, 5546.944530554683, 5552.028395030874, 5564.554835613161, 5568.276124103881, 5458.044842152627, 5508.779648814063, 5551.528120109796, 5554.555635549166, 5567.972160139194, 5498.257735205756, 5588.91616625751, 5594.468525490092, 5606.775728970836, 5636.830895073154, 5569.582281328917, 5619.241402410684, 5624.100143976964, 5651.604387692883, 5662.8301150965535, 5262.973685131588, 5437.619366644337, 5479.4520547945285, 5480.3971563128025, 5492.093804522249, 13494.0, 13794.586162415144, 13836.930815345859, 13873.58379248624, 13887.305634718223, 1956.5597740508415, 1958.0209895052474, 2225.2100504320897, 2242.1479837661745, 2264.6942662740485, 9278.211352035085, 9846.852297215548, 10091.0, 10906.5092070857, 15560.22198890051, 1659.601695593049, 1785.616092540097, 1817.963640727178, 1827.826356496144, 1832.0748022248679, 115682.42158789153, 116085.0, 116258.41870790592, 116995.0, 118976.0, 11771.941140294244, 11772.411379430994, 11822.881771182178, 11855.881441185651, 11897.405129743478, 15274.618134546503, 15296.847031529765, 15361.078335299917, 15375.308111135026, 15393.23033848303, 7998.400119990907, 8106.63520141595, 8769.42998705095, 8901.688440904667, 8990.325725570608, 7283.927160728324, 8710.60802263887, 8726.738197854074, 8757.97849741838, 8888.200061994452, 4088.8977775555254, 4483.103379324083, 4566.70316429431, 4689.812407503729, 4780.3746726112595, 1919.395390452003, 1966.2165528925266, 1970.6649869522216, 1978.920843166286, 1984.5270776141751, 824.9463784853965, 827.8799574061848, 854.5001174313096, 909.3134683314056, 979.6921110317805, 19943.800561994485, 20740.170393184406, 20843.791562084185, 21679.566408671737, 21953.780462195493, 703.6974101136458, 989.5744829723144, 995.4674249276602, 1022.0239671114217, 1030.5156576409076, 10651.893481065244, 10956.28784129029, 10993.780124397626, 11005.61480348184, 11013.559457621766, 463106.0, 465860.0, 466121.0, 619779.0, 640416.0, 144402.0, 150174.49825501605, 150797.99999999782, 153458.0, 155416.66874996884, 1027.6660085472251, 1030.907218350367, 1048.905598496139, 1102.685734565661, 1103.2442776697987, 18049.819501805337, 18108.366207182684, 18202.45279151287, 18211.089445527406, 18301.908490457463, 54928.72535637456, 56534.0, 57233.0, 57740.4225957735, 58079.83840323254, 76326.0, 81797.59101204456, 82191.17808821835, 84074.15925840664, 85428.43761521838, 7720.382369410321, 7900.6049697513745, 8012.399070069885, 8013.3188678960605, 8043.316318113026, 8844.601992910206, 8992.145746154105, 12264.509419623471, 12620.936895315464, 12703.80944285855, 10775.0, 10852.240343175901, 11246.100311975186, 11247.325160490396, 11297.322160670386, 10530.0, 10753.731156721145, 10855.968682975104, 11020.0, 11424.771504570028, 11124.499397526968, 11128.833067504156, 11616.825747613962, 11626.476808543805, 11683.240589361665, 7059.5764254144915, 8181.79545511367, 8250.38122140854, 8364.87452688198, 8595.011573669108, 9487.478188699688, 10985.560577576807, 11026.228164028442, 11245.493952772309, 11399.20205585601], "value": 9390.118335962305, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.7584834729744, 1564.116274305018, 1590.165163289273, 1812.854971602272, 1963.047921757947, 8000.239977202164, 8722.215000649949, 8958.686445974392, 8992.460452372863, 9142.131497507742, 8666.73999780006, 8900.688475903346, 8912.465252084863, 8915.242204412632, 8938.597763100659, 1821.280594165304, 2245.741739699939, 2251.8423710340285, 2428.611422172453, 2464.8028157747426, 2253.357793028987, 2297.4026753044163, 2440.6339049142593, 2504.749525047499, 2519.105717470299, 2430.927072187837, 2431.7689819467123, 2441.3652450362943, 2442.621393683973, 2446.657467954488, 2471.9011239550396, 2484.3416494628937, 2487.5522405966917, 2488.713797913245, 2490.2529241227558, 2192.3422973108163, 2422.4428381472185, 2489.464765075499, 2491.451880586268, 2513.421912960011, 8955.910440895637, 9024.23294020009, 9035.909640903572, 9042.773930651787, 9043.457392556467, 5420.214068959979, 5612.270404847384, 5612.69130197839, 5621.7751289948355, 5622.8313150605545, 3551.6803487685975, 3624.4563315502623, 3731.738778285521, 3741.9625803741887, 3812.7712337259854, 1001.324106228293, 1031.2626472072504, 1035.3580779916424, 1041.4896700616719, 1045.2892033417272, 12184.147109702324, 12226.633201003984, 12230.755384892253, 12231.633051008484, 12234.08244381666, 4892.902141957212, 4893.975530122398, 5020.096382651085, 5021.14640511114, 5037.143685573388, 8781.121887811169, 10361.689149325532, 10380.221483388625, 10382.961703829707, 10390.0, 5551.8889622207325, 5559.11054231321, 5571.777128914879, 5579.972100139555, 5582.051051321285, 4560.361549383089, 4904.411470623477, 4963.875903102379, 5086.593072554187, 5133.717645529457, 3174.904752857418, 4079.7964600442942, 4107.199096176277, 4216.536181020086, 4220.85227017053, 1335.7662409078373, 1362.346073884541, 1362.414161910388, 1375.0855680972043, 1375.6629625741757, 771.4060173666358, 908.8636704494246, 914.8444764390069, 951.619352259088, 954.9761255968517, 5287.841364759063, 5473.370562385297, 6029.969850150809, 6112.205413296243, 6196.783112591038, 933.118203297877, 945.7210123013728, 973.4743238651049, 988.8467287570438, 992.1071036067597, 4017.9754162688328, 4053.108316170438, 4061.9796901015898, 4159.625633692982, 4159.875203743893, 9270.582823772811, 11051.557937682403, 11115.444227788577, 11118.833217501744, 11169.553217871357, 9206.447613143233, 10963.56145754177, 11078.002979731862, 11082.168837337233, 11096.223264371416, 7958.602069896482, 8332.04181519133, 8339.24946754795, 8381.748547543584, 8478.448900821428, 6063.787767428119, 6080.726367313393, 6201.379862013762, 6211.534134939988, 6256.530760192913, 9910.860251071219, 10212.540435680561, 10475.004874536908, 10492.0, 10511.001454861776, 8926.598303076507, 10260.179185665278, 10658.0, 10674.0, 10684.57261709523, 7079.787606371817, 7286.781396558112, 7779.4554381192775, 7918.564478953713, 7926.603669816602, 6788.981652752127, 7524.548527088174, 8476.533790641573, 8611.181937715906, 8658.740237792876, 7341.963290183515, 7611.695532178873, 7695.961520192363, 7730.265624765639, 7797.7660670179985, 7568.508046976929, 7578.242175782378, 7611.0, 7690.961545192238, 7768.300852923264, 6711.865762684816, 7032.542884712478, 7057.717691292496, 7136.6788494518905, 7138.85722285541, 4896.804127834846, 4957.925631115464, 4984.900301994012, 5067.46791586878, 5205.089109405763, 4238.385434112098, 4777.593904518155, 4965.45380008206, 5141.460146684544, 5308.761105750328], "value": 4684.2711960082215, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.78798417076376, 313.7411635400794, 327.6952434236159, 332.75708732625196, 334.97822641528296, 4406.71356361837, 4646.163690535702, 4910.680805747633, 5300.787968481261, 5402.351717793866, 4609.285560738091, 5211.400688920775, 5226.928479661669, 5258.318257688577, 5260.105782017056, 4088.6115818997146, 4098.385242213677, 4110.321796903508, 4124.968757810548, 4130.277201489742, 3917.9020524486973, 3998.5401678806875, 4084.387341898724, 4148.896277593054, 4150.273702102135, 3372.342393233308, 3779.7354185207046, 3954.051027753333, 4042.3532234842432, 4106.733062350952, 3342.7827191232736, 3558.6619271169197, 3967.7303262955866, 4062.939055914164, 4129.793510324502, 3148.480500717379, 3291.637919828817, 3548.769329993542, 3563.768355056913, 3582.6059133495296, 4482.865514034552, 4789.616830653539, 5137.379936232548, 5147.356580427447, 5211.530962213381, 4183.874483765492, 4353.695241333108, 4357.694961352707, 4416.514183439819, 4654.371659825914, 5349.0, 5619.297587801525, 5624.3250809902665, 5634.971825140848, 5637.774489020434, 3856.2865869814173, 3857.633524815138, 3861.613838616144, 3889.7860617666015, 3890.8716472223305, 5064.468230835746, 5391.487808658171, 5739.368669446358, 5902.527797776168, 5987.922174008633, 3417.8120203388553, 3848.9037774055873, 3872.1094148345474, 3910.276598829226, 3921.450996860442, 3968.7420317679257, 3999.5200575931076, 4001.919961600752, 4011.2579172852534, 4011.338129208706, 3677.191017976041, 4370.322599997005, 4444.955550444519, 4663.883402914954, 4671.8364857229835, 5279.970405770838, 5705.5720820938595, 5722.599418040781, 5730.627509211888, 5748.48263656273, 1270.7585558743808, 1322.543722415765, 1352.3373546961918, 1361.1220762608061, 1364.3655700099303, 706.7173130747742, 799.2566912771179, 835.1731785532401, 843.5191940593803, 849.2781136034322, 4061.0557345923967, 4239.27932251518, 4321.503027151855, 4439.467263928349, 4469.128519938646, 4620.792064357112, 4880.853574392774, 4921.5303211236, 4926.423544465737, 4930.778114984834, 4132.876013719593, 4334.43652325202, 4417.249067658506, 4439.267520859086, 4467.369410165331, 2368.325027367192, 2593.727658595858, 2613.294410509152, 2615.9869200654257, 2616.3328351270306, 4257.1911336846515, 4403.823847046082, 4562.954370456319, 4964.90070198594, 5082.974585127051, 4457.531959144308, 4905.241915342112, 5016.413264515889, 5029.497050294941, 5124.871878203075, 4627.5835174833755, 4715.830717869819, 5061.721605311744, 5084.584822209421, 5116.974415127975, 4412.183746006999, 4418.447694038246, 4555.9452087642685, 4837.4920633334405, 4849.127157111754, 4566.840160594364, 4601.504511033994, 4845.8061677532505, 4846.781894814671, 4896.6572339935865, 4455.465344158722, 4540.886477837948, 4725.464224127241, 5108.182690769459, 5189.714565698923, 2971.7815695564714, 3086.2438702518025, 3231.6929891660257, 3407.7444191685227, 3434.9484757729156, 4126.286342291961, 4901.509849015212, 4906.375089327193, 4953.7523123844385, 4995.400551933792, 3831.482749828849, 3943.0, 4188.539260681262, 4446.533114022981, 4718.891060600825, 4018.693924474499, 4097.009183296151, 4298.097399546005, 4349.846825821112, 4508.797104130387, 4136.689748268952, 4144.129732756155, 4385.780710964503, 4403.524819185609, 4707.882302942454, 3756.708407871251, 4139.441175441277, 4406.008648054162, 4490.977545112253, 4785.569298762989, 3846.9038274043373, 4460.9107817844115, 4604.792784324646, 4607.14767767964, 4626.409210841939], "value": 3750.6746801241975, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [643.9356064393561, 686.1628812848325, 707.2856415020827, 710.754789597589, 717.0713925466521, 15001.39994400224, 15376.308066137022, 15572.688546229068, 15643.530694079196, 15649.921750391246, 14099.0, 14104.435822567111, 14132.434702611883, 14151.504697335595, 14178.71642567148, 678.7420780103554, 694.8783962806514, 850.408965768791, 850.506706110456, 875.1336177184605, 855.9315254779634, 858.3133493205441, 865.7748985263813, 868.3010176807699, 869.3306154261215, 926.8795056642628, 933.3280038372361, 934.2992755433424, 934.6121359635777, 935.6584846531001, 711.7117567385217, 892.0900681305033, 916.120524296676, 918.4994178172911, 926.4950601921935, 687.99552653126, 688.3288793426419, 689.1798759476208, 690.6408667492875, 691.3225039461356, 14954.0, 14993.025453345497, 15020.17389043602, 15027.94804363695, 15029.699406011929, 12365.0, 12406.317652529107, 12433.875661243363, 12497.062720295924, 12498.875011249862, 10859.83710244347, 10982.176336774774, 11009.779804403946, 11433.885661143366, 11581.42092895352, 4111.8560850370395, 4130.938035929464, 4176.895577610554, 4237.470316210473, 4272.722273052273, 83869.0, 84308.0, 86855.74870387303, 86859.99999999937, 86971.56514217451, 9403.529823508798, 9576.808463830685, 9655.0, 9791.706248812547, 9817.509124543743, 22187.445313867283, 22206.444838879157, 22209.777902221093, 22229.0, 22301.442463938532, 6192.783252586138, 7318.0, 7351.228121047213, 7360.33756961876, 7367.852642947111, 3964.7621142731527, 3997.060690737674, 4036.7981600920425, 4040.4545386372465, 4047.332190188586, 2806.789490788199, 3529.7882127072457, 3539.0975301298004, 3544.2379888324135, 3546.0, 618.9907151392732, 629.5309994054459, 631.0691729698749, 651.5829868883918, 653.0628548033587, 511.64185070450833, 529.9973500132475, 545.664416383921, 556.6993823335354, 570.6433479075578, 7638.618069096634, 7698.692052317957, 7815.570143642154, 7927.920720792834, 7956.721514746956, 551.834449665107, 568.5366426362584, 569.9031164702011, 571.7770069672831, 582.7785441532276, 3571.785692858437, 3716.1452865841006, 3781.829817658157, 3808.6000969898314, 3961.207758448264, 73800.0, 94735.52632236795, 95859.0, 96121.0, 97714.51142744241, 82656.17343826605, 83413.74879376814, 84092.57953710192, 84445.57777211074, 87867.1213287859, 52163.73918130461, 66529.6694066116, 76268.23731762722, 77036.0, 77973.08692116133, 6324.367563243639, 6855.245922948372, 7223.853019624146, 7261.52800068015, 7353.301436363537, 38283.0, 44024.11951761011, 44966.20561529599, 45157.96789144431, 46534.53465465302, 52531.47468525266, 60374.69812650909, 61202.081968771396, 63971.72056558749, 66768.0, 9714.562844672148, 10038.29731918788, 10080.899191007997, 10158.18734501253, 10341.276110672481, 10508.417105473101, 10542.8418573723, 10596.576136954436, 10682.359058456517, 10692.732681683021, 9790.80418391614, 9863.161631261422, 9926.404415735078, 10108.140808031105, 10359.22305827081, 9789.412635241908, 10097.545610447694, 10187.337823041478, 10369.274150809371, 11000.394978276272, 8073.3541316693345, 8335.333173346238, 10054.54754536033, 10070.69787906364, 10739.979701928305, 8341.791455213426, 8360.038595561587, 8487.321014318963, 8687.522186279815, 8749.562521873753, 7531.623418829146, 8109.59452027408, 8538.573071346284, 8542.40203185771, 8569.185927336894], "value": 6637.8219149639835, "unit": "score"}], "name": "dromaeo_css", "value": 7060.987240642449, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 105.0, 112.0, 114.0, 157.0, 166.0, 112.0, 111.0, 112.0, 111.0], "value": 121.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 121.0, 142.0, 197.0, 116.0, 140.0, 149.0, 126.0, 209.0, 117.0], "value": 147.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 183.0, 185.0, 181.0, 184.0, 183.0, 186.0, 182.0, 185.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 57.0, 125.0, 57.0, 61.0, 56.0, 59.0, 110.0, 56.0], "value": 73.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 137.0, 152.0, 79.0, 79.0, 76.0, 101.0, 78.0, 80.0, 100.0], "value": 97.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 85.0, 88.0, 85.0, 88.0, 86.0, 88.0, 85.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 95.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 134.0, 114.0, 87.0, 84.0, 84.0, 84.0, 99.0, 140.0, 133.0], "value": 108.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 58.0, 58.0, 58.0, 58.0, 57.0, 118.0, 57.0, 56.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 72.0, 73.0, 70.0, 68.0, 67.0, 69.0, 67.0, 68.0], "value": 68.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 85.0, 85.0, 84.0, 82.0, 85.0, 88.0, 102.0, 86.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 136.0, 135.0, 141.0, 139.0, 141.0, 135.0, 208.0, 133.0], "value": 144.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 160.0, 158.0, 163.0, 165.0, 159.0, 161.0, 164.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 82.0, 84.0, 66.0, 65.0, 65.0, 64.0, 66.0, 64.0, 64.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1503.7, "alertThreshold": 2.0}]} 08:57:28 INFO - Return code: 0 08:57:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:57:29 ERROR - # TBPL SUCCESS # 08:57:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:57:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:57:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:57:29 INFO - 2016-05-19 08:57:29.044 screenresolution[1675:10147] starting screenresolution argv=screenresolution get 08:57:29 INFO - 2016-05-19 08:57:29.047 screenresolution[1675:10147] Display 0: 1600x1200x32@60 08:57:29 INFO - 2016-05-19 08:57:29.052 screenresolution[1676:10150] starting screenresolution argv=screenresolution list 08:57:29 INFO - Available Modes on Display 0 08:57:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:57:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:57:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:57:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:57:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:57:29 INFO - Intel Iris: 08:57:29 INFO - Chipset Model: Intel Iris 08:57:29 INFO - Type: GPU 08:57:29 INFO - Bus: Built-In 08:57:29 INFO - VRAM (Dynamic, Max): 1536 MB 08:57:29 INFO - Vendor: Intel (0x8086) 08:57:29 INFO - Device ID: 0x0a2e 08:57:29 INFO - Revision ID: 0x0009 08:57:29 INFO - Displays: 08:57:29 INFO - Display: 08:57:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:57:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:57:29 INFO - Display Serial Number: 1600x1200 60 08:57:29 INFO - Main Display: Yes 08:57:29 INFO - Mirror: Off 08:57:29 INFO - Online: Yes 08:57:29 INFO - Rotation: Supported 08:57:29 INFO - Return code: 0 08:57:29 INFO - Running post-action listener: _resource_record_post_action 08:57:29 INFO - ##### 08:57:29 INFO - ##### Finished run-tests step (success) 08:57:29 INFO - ##### 08:57:29 INFO - Running post-run listener: _resource_record_post_run 08:57:29 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37991936; Write bytes: 695614464; Read time: 394; Write time: 1893 08:57:29 INFO - TinderboxPrint: CPU usage
54.1% 08:57:29 INFO - TinderboxPrint: I/O read bytes / time
37,991,936 / 394 08:57:29 INFO - TinderboxPrint: I/O write bytes / time
695,614,464 / 1,893 08:57:29 INFO - TinderboxPrint: CPU idle
2,226.5 (45.9%) 08:57:29 INFO - TinderboxPrint: CPU system
970.1 (20.0%) 08:57:29 INFO - TinderboxPrint: CPU user
1,649.0 (34.0%) 08:57:29 INFO - TinderboxPrint: Swap in / out
433,504,256 / 0 08:57:29 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 374860288; Write bytes: 368986112; Read time: 28136; Write time: 721 08:57:29 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 37033472; Write bytes: 297464832; Read time: 379; Write time: 1112 08:57:29 INFO - Running post-run listener: _upload_blobber_files 08:57:29 INFO - Blob upload gear active. 08:57:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:57:29 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 08:57:29 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'] 08:57:29 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 08:57:30 INFO - (blobuploader) - INFO - Open directory for files ... 08:57:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:57:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:57:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:57:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:57:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:57:31 INFO - (blobuploader) - INFO - Done attempting. 08:57:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:57:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:57:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:57:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:57:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:57:32 INFO - (blobuploader) - INFO - Done attempting. 08:57:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:57:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:57:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:57:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:57:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:57:34 INFO - (blobuploader) - INFO - Done attempting. 08:57:34 INFO - (blobuploader) - INFO - Iteration through files over. 08:57:34 INFO - Return code: 0 08:57:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:57:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:57:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/49b8af2359d279252c66b3367588041b595b0b787eb096937c7344c0901466bf7e144d08d7b385ef513118bf96a2bee4d7ef59bbe79f541c5dc96c90611965cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/005a86a82b0cd1018570a34098b517280d84956f58b646ad531d98c7cb569fdbdd165ed6296e442fb6cea6296b2271de8e3193a8b67e33ce505cc9c13a6a1b47", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc171f5eb30cde96f23d7c830fe35fb43a07ebf3f757c1368593d1c0811856b880c8b8e32fee4a2ea0795333a29b1cd7a6f26650afaf35bca45bce54db47e53e"} 08:57:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:57:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:57:34 INFO - Contents: 08:57:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/49b8af2359d279252c66b3367588041b595b0b787eb096937c7344c0901466bf7e144d08d7b385ef513118bf96a2bee4d7ef59bbe79f541c5dc96c90611965cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/005a86a82b0cd1018570a34098b517280d84956f58b646ad531d98c7cb569fdbdd165ed6296e442fb6cea6296b2271de8e3193a8b67e33ce505cc9c13a6a1b47", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc171f5eb30cde96f23d7c830fe35fb43a07ebf3f757c1368593d1c0811856b880c8b8e32fee4a2ea0795333a29b1cd7a6f26650afaf35bca45bce54db47e53e"} 08:57:34 INFO - Running post-run listener: copy_logs_to_upload_dir 08:57:34 INFO - Copying logs to upload dir... 08:57:34 INFO - mkdir: /builds/slave/test/build/upload/logs 08:57:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.753726 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-05-19 08:57:34.805451) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 08:57:34.808817) ========= 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.IMuiCwMj5B/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.vcVnIgXPF1/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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/49b8af2359d279252c66b3367588041b595b0b787eb096937c7344c0901466bf7e144d08d7b385ef513118bf96a2bee4d7ef59bbe79f541c5dc96c90611965cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/005a86a82b0cd1018570a34098b517280d84956f58b646ad531d98c7cb569fdbdd165ed6296e442fb6cea6296b2271de8e3193a8b67e33ce505cc9c13a6a1b47", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc171f5eb30cde96f23d7c830fe35fb43a07ebf3f757c1368593d1c0811856b880c8b8e32fee4a2ea0795333a29b1cd7a6f26650afaf35bca45bce54db47e53e"} build_url:https://queue.taskcluster.net/v1/task/V_hfS7GOSI-9p_5pA8QWdA/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010847 build_url: 'https://queue.taskcluster.net/v1/task/V_hfS7GOSI-9p_5pA8QWdA/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/49b8af2359d279252c66b3367588041b595b0b787eb096937c7344c0901466bf7e144d08d7b385ef513118bf96a2bee4d7ef59bbe79f541c5dc96c90611965cf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/005a86a82b0cd1018570a34098b517280d84956f58b646ad531d98c7cb569fdbdd165ed6296e442fb6cea6296b2271de8e3193a8b67e33ce505cc9c13a6a1b47", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc171f5eb30cde96f23d7c830fe35fb43a07ebf3f757c1368593d1c0811856b880c8b8e32fee4a2ea0795333a29b1cd7a6f26650afaf35bca45bce54db47e53e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 08:57:34.840773) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 08:57:34.841112) ========= 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.IMuiCwMj5B/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.vcVnIgXPF1/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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.004996 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 08:57:34.893150) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 08:57:34.893916) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 08:57:34.894664) ========= ========= Total master_lag: 1.94 =========