builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0127 starttime: 1464216022.24 results: success (0) buildid: 20160525135343 builduid: 7b118da9cbef4c0daa98c34ea0a5c59e revision: 3531b8206f7051e2286f860e2c449bb5ff7f4a4c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 15:40:22.243788) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 15:40:22.244706) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 15:40:22.245328) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 15:40:22.268899) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 15:40:22.269611) ========= 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.vIG2C8zznB/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.Em5W69yZWc/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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-25 15:40:22-- 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% 25.4M=0s 2016-05-25 15:40:22 (25.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.087931 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 15:40:22.383903) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 15:40:22.384630) ========= 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.vIG2C8zznB/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.Em5W69yZWc/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.028137 ========= master_lag: 0.12 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 15:40:22.537336) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 15:40:22.537737) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 3531b8206f7051e2286f860e2c449bb5ff7f4a4c --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 3531b8206f7051e2286f860e2c449bb5ff7f4a4c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vIG2C8zznB/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.Em5W69yZWc/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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-25 15:40:22,617 truncating revision to first 12 chars 2016-05-25 15:40:22,617 Setting DEBUG logging. 2016-05-25 15:40:22,617 attempt 1/10 2016-05-25 15:40:22,617 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/3531b8206f70?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-25 15:40:23,340 unpacking tar archive at: fx-team-3531b8206f70/testing/mozharness/ program finished with exit code 0 elapsedTime=0.975268 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 15:40:23.535766) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 15:40:23.536167) ========= script_repo_revision: 3531b8206f7051e2286f860e2c449bb5ff7f4a4c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 15:40:23.536598) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 15:40:23.537003) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 15:40:23.553468) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-25 15:40:23.554042) ========= /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.vIG2C8zznB/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.Em5W69yZWc/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 15:40:23 INFO - MultiFileLogger online at 20160525 15:40:23 in /builds/slave/test 15:40:23 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 15:40:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:40:23 INFO - {'append_to_log': False, 15:40:23 INFO - 'base_work_dir': '/builds/slave/test', 15:40:23 INFO - 'blob_upload_branch': 'Fx-Team', 15:40:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:40:23 INFO - 'branch': 'Fx-Team', 15:40:23 INFO - 'buildbot_json_path': 'buildprops.json', 15:40:23 INFO - 'config_files': ('talos/mac_config.py',), 15:40:23 INFO - 'default_actions': ('clobber', 15:40:23 INFO - 'read-buildbot-config', 15:40:23 INFO - 'download-and-extract', 15:40:23 INFO - 'populate-webroot', 15:40:23 INFO - 'create-virtualenv', 15:40:23 INFO - 'install', 15:40:23 INFO - 'run-tests'), 15:40:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:40:23 INFO - 'download_minidump_stackwalk': True, 15:40:23 INFO - 'download_symbols': 'ondemand', 15:40:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:40:23 INFO - 'tooltool.py': '/tools/tooltool.py', 15:40:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:40:23 INFO - '/tools/misc-python/virtualenv.py')}, 15:40:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:40:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:40:23 INFO - 'installer_path': 'installer.exe', 15:40:23 INFO - 'log_level': 'info', 15:40:23 INFO - 'log_name': 'talos', 15:40:23 INFO - 'log_to_console': True, 15:40:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:40:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:40:23 INFO - 'opt_config_files': (), 15:40:23 INFO - 'pip_index': False, 15:40:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:40:23 INFO - 'cmd': ('bash', 15:40:23 INFO - '-c', 15:40:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:40:23 INFO - 'enabled': True, 15:40:23 INFO - 'halt_on_failure': False, 15:40:23 INFO - 'name': 'check_screen_resolution'},), 15:40:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:40:23 INFO - 'cmd': ('bash', 15:40:23 INFO - '-c', 15:40:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:40:23 INFO - 'enabled': True, 15:40:23 INFO - 'halt_on_failure': False, 15:40:23 INFO - 'name': 'check_screen_resolution'},), 15:40:23 INFO - 'run_cmd_checks_enabled': True, 15:40:23 INFO - 'sps_profile': False, 15:40:23 INFO - 'sps_profile_interval': 0, 15:40:23 INFO - 'suite': 'dromaeojs', 15:40:23 INFO - 'system_bits': '32', 15:40:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:40:23 INFO - 'title': 't-yosemite-r7-0127', 15:40:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:40:23 INFO - 'use_talos_json': True, 15:40:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:40:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:40:23 INFO - 'work_dir': 'build'} 15:40:23 INFO - ##### 15:40:23 INFO - ##### Running clobber step. 15:40:23 INFO - ##### 15:40:23 INFO - Running pre-action listener: _resource_record_pre_action 15:40:23 INFO - Running main action method: clobber 15:40:23 INFO - rmtree: /builds/slave/test/build 15:40:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:40:25 INFO - Running post-action listener: _resource_record_post_action 15:40:25 INFO - ##### 15:40:25 INFO - ##### Finished clobber step (success) 15:40:25 INFO - ##### 15:40:25 INFO - ##### 15:40:25 INFO - ##### Running read-buildbot-config step. 15:40:25 INFO - ##### 15:40:25 INFO - Running pre-action listener: _resource_record_pre_action 15:40:25 INFO - Running main action method: read_buildbot_config 15:40:25 INFO - Using buildbot properties: 15:40:25 INFO - { 15:40:25 INFO - "project": "", 15:40:25 INFO - "product": "firefox", 15:40:25 INFO - "script_repo_revision": "production", 15:40:25 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 15:40:25 INFO - "repository": "", 15:40:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 15:40:25 INFO - "buildid": "20160525135343", 15:40:25 INFO - "pgo_build": "False", 15:40:25 INFO - "basedir": "/builds/slave/test", 15:40:25 INFO - "buildnumber": 21, 15:40:25 INFO - "slavename": "t-yosemite-r7-0127", 15:40:25 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 15:40:25 INFO - "platform": "yosemite_r7", 15:40:25 INFO - "scheduler": "tests-fx-team-macosx64-talos", 15:40:25 INFO - "branch": "fx-team", 15:40:25 INFO - "repo_path": "integration/fx-team", 15:40:25 INFO - "revision": "3531b8206f7051e2286f860e2c449bb5ff7f4a4c", 15:40:25 INFO - "stage_platform": "macosx64", 15:40:25 INFO - "builduid": "7b118da9cbef4c0daa98c34ea0a5c59e", 15:40:25 INFO - "slavebuilddir": "test" 15:40:25 INFO - } 15:40:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 15:40:25 INFO - Running post-action listener: _resource_record_post_action 15:40:25 INFO - ##### 15:40:25 INFO - ##### Finished read-buildbot-config step (success) 15:40:25 INFO - ##### 15:40:25 INFO - ##### 15:40:25 INFO - ##### Running download-and-extract step. 15:40:25 INFO - ##### 15:40:25 INFO - Running pre-action listener: _resource_record_pre_action 15:40:25 INFO - Running main action method: download_and_extract 15:40:25 INFO - mkdir: /builds/slave/test/build/tests 15:40:25 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')]} 15:40:25 INFO - https://queue.taskcluster.net/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:40:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:40:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:40:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/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 15:40:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/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 15:40:27 INFO - Downloaded 1199 bytes. 15:40:27 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 15:40:27 INFO - Using the following test package requirements: 15:40:27 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:40:27 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:40:27 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 15:40:27 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:40:27 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 15:40:27 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:40:27 INFO - u'jsshell-mac.zip'], 15:40:27 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:40:27 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 15:40:27 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:40:27 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:40:27 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 15:40:27 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:40:27 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 15:40:27 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:40:27 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 15:40:27 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:40:27 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 15:40:27 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:40:27 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')]} 15:40:27 INFO - https://queue.taskcluster.net/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:40:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:40:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:40:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/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 15:40:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/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 15:40:28 INFO - Downloaded 18634713 bytes. 15:40:28 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'] 15:40:28 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 15:40:31 INFO - Return code: 0 15:40:31 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 15:40:31 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')]} 15:40:31 INFO - https://queue.taskcluster.net/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:40:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:40:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:40:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/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 15:40:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/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 15:40:33 INFO - Downloaded 18634713 bytes. 15:40: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'] 15:40: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 15:40:36 INFO - Return code: 0 15:40:36 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')]} 15:40:36 INFO - https://queue.taskcluster.net/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:40:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:40:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:40:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/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 15:40:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/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 15:40:38 INFO - Downloaded 10834034 bytes. 15:40: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'] 15:40: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 15:40:38 INFO - Return code: 0 15:40:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:38 INFO - https://queue.taskcluster.net/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:40:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:40:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:40:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 15:40:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GIKY64HMQIe4lBwT89m40w/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 15:40:42 INFO - Downloaded 110518057 bytes. 15:40:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:40:42 INFO - mkdir: /builds/slave/test/properties 15:40:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:40:42 INFO - Writing to file /builds/slave/test/properties/build_url 15:40:42 INFO - Contents: 15:40:42 INFO - build_url:https://queue.taskcluster.net/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:40:43 INFO - Running post-action listener: _resource_record_post_action 15:40:43 INFO - Running post-action listener: set_extra_try_arguments 15:40:43 INFO - ##### 15:40:43 INFO - ##### Finished download-and-extract step (success) 15:40:43 INFO - ##### 15:40:43 INFO - ##### 15:40:43 INFO - ##### Running populate-webroot step. 15:40:43 INFO - ##### 15:40:43 INFO - Running pre-action listener: _resource_record_pre_action 15:40:43 INFO - Running main action method: populate_webroot 15:40:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:40:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:40:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:40:43 INFO - '--tppagecycles', 15:40:43 INFO - '7'], 15:40:43 INFO - 'tests': ['tsvgm']}}, 15:40:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:40:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:40:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:40:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:40:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:40:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:40:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:43 INFO - 'talos_options': ['--disable-e10s'], 15:40:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:40:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:40:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:43 INFO - 'talos_options': ['--disable-e10s'], 15:40:43 INFO - 'tests': ['damp', 'tps']}, 15:40:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:43 INFO - 'tests': ['damp', 'tps']}, 15:40:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:40:43 INFO - 'tests': ['dromaeo_dom']}, 15:40:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:40:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:40:43 INFO - 'tests': ['a11yr', 15:40:43 INFO - 'ts_paint', 15:40:43 INFO - 'tpaint', 15:40:43 INFO - 'sessionrestore', 15:40:43 INFO - 'sessionrestore_no_auto_restore', 15:40:43 INFO - 'tabpaint']}, 15:40:43 INFO - 'other-e10s': {'tests': ['a11yr', 15:40:43 INFO - 'ts_paint', 15:40:43 INFO - 'tpaint', 15:40:43 INFO - 'sessionrestore', 15:40:43 INFO - 'sessionrestore_no_auto_restore', 15:40:43 INFO - 'tabpaint']}, 15:40:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:40:43 INFO - 'ts_paint', 15:40:43 INFO - 'tpaint', 15:40:43 INFO - 'sessionrestore', 15:40:43 INFO - 'sessionrestore_no_auto_restore', 15:40:43 INFO - 'tabpaint']}, 15:40:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:40:43 INFO - 'ts_paint', 15:40:43 INFO - 'tpaint', 15:40:43 INFO - 'sessionrestore', 15:40:43 INFO - 'sessionrestore_no_auto_restore', 15:40:43 INFO - 'tabpaint']}, 15:40:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:40:43 INFO - 'tests': ['a11yr', 15:40:43 INFO - 'ts_paint', 15:40:43 INFO - 'tpaint', 15:40:43 INFO - 'sessionrestore', 15:40:43 INFO - 'sessionrestore_no_auto_restore', 15:40:43 INFO - 'tabpaint']}, 15:40:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:40:43 INFO - 'tests': ['a11yr', 15:40:43 INFO - 'ts_paint', 15:40:43 INFO - 'tpaint', 15:40:43 INFO - 'sessionrestore', 15:40:43 INFO - 'sessionrestore_no_auto_restore', 15:40:43 INFO - 'tabpaint']}, 15:40:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:40:43 INFO - 'tests': ['tsvgx', 15:40:43 INFO - 'tsvgr_opacity', 15:40:43 INFO - 'tart', 15:40:43 INFO - 'tscrollx', 15:40:43 INFO - 'cart']}, 15:40:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:40:43 INFO - 'tsvgr_opacity', 15:40:43 INFO - 'tart', 15:40:43 INFO - 'tscrollx', 15:40:43 INFO - 'cart']}, 15:40:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:43 INFO - 'talos_options': ['--disable-e10s'], 15:40:43 INFO - 'tests': ['tp5o']}, 15:40:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:43 INFO - 'tests': ['tp5o']}, 15:40:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:43 INFO - 'talos_options': ['--disable-e10s', 15:40:43 INFO - '--xperf_path', 15:40:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:40:43 INFO - 'tests': ['tp5n']}, 15:40:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:40:43 INFO - 'talos_options': ['--xperf_path', 15:40:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:40:43 INFO - 'tests': ['tp5n']}}, 15:40:43 INFO - 'talos.zip': {'path': '', 15:40:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:40:43 INFO - Running post-action listener: _resource_record_post_action 15:40:43 INFO - ##### 15:40:43 INFO - ##### Finished populate-webroot step (success) 15:40:43 INFO - ##### 15:40:43 INFO - ##### 15:40:43 INFO - ##### Running create-virtualenv step. 15:40:43 INFO - ##### 15:40:43 INFO - Running pre-action listener: _resource_record_pre_action 15:40:43 INFO - Running main action method: create_virtualenv 15:40:43 INFO - Creating virtualenv /builds/slave/test/build/venv 15:40: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 15:40:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:40:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:40:43 INFO - Using real prefix '/tools/python27' 15:40:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:40:44 INFO - Installing distribute.............................................................................................................................................................................................done. 15:40:47 INFO - Installing pip.................done. 15:40:47 INFO - Return code: 0 15:40:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:40: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')]} 15:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40: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')]} 15:40:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40: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 0x1075baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98dbd99860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10745ed30>, '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 0x107282db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107455b20>, '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.vIG2C8zznB/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.Em5W69yZWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 15:40: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 15:40: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 15:40:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vIG2C8zznB/Render', 15:40:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:47 INFO - 'HOME': '/Users/cltbld', 15:40:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:47 INFO - 'LOGNAME': 'cltbld', 15:40:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:47 INFO - 'MOZ_NO_REMOTE': '1', 15:40:47 INFO - 'NO_EM_RESTART': '1', 15:40:47 INFO - 'PAGER': '/bin/cat', 15:40:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:47 INFO - 'PWD': '/builds/slave/test', 15:40:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:47 INFO - 'SHELL': '/bin/bash', 15:40:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Em5W69yZWc/Listeners', 15:40:47 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 15:40:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:47 INFO - 'USER': 'cltbld', 15:40:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:47 INFO - 'XPC_FLAGS': '0x0', 15:40:47 INFO - 'XPC_SERVICE_NAME': '0', 15:40:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40: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 15:40:47 INFO - Cleaning up... 15:40:47 INFO - Return code: 0 15:40:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:40: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')]} 15:40:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40: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')]} 15:40:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40: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 0x1075baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98dbd99860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10745ed30>, '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 0x107282db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107455b20>, '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.vIG2C8zznB/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.Em5W69yZWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 15:40: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 15:40: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 15:40:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vIG2C8zznB/Render', 15:40:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:47 INFO - 'HOME': '/Users/cltbld', 15:40:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:47 INFO - 'LOGNAME': 'cltbld', 15:40:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:47 INFO - 'MOZ_NO_REMOTE': '1', 15:40:47 INFO - 'NO_EM_RESTART': '1', 15:40:47 INFO - 'PAGER': '/bin/cat', 15:40:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:47 INFO - 'PWD': '/builds/slave/test', 15:40:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:47 INFO - 'SHELL': '/bin/bash', 15:40:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Em5W69yZWc/Listeners', 15:40:47 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 15:40:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:47 INFO - 'USER': 'cltbld', 15:40:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:47 INFO - 'XPC_FLAGS': '0x0', 15:40:47 INFO - 'XPC_SERVICE_NAME': '0', 15:40:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:47 INFO - Downloading/unpacking psutil>=3.1.1 15:40: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 15:40: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 15:40: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 15:40: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 15:40: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 15:40: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 15:40:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:40:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:40:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:40:50 INFO - Installing collected packages: psutil 15:40:50 INFO - Running setup.py install for psutil 15:40:50 INFO - building 'psutil._psutil_osx' extension 15:40: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 15:40: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 15:40: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 15:40: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 15:40:51 INFO - building 'psutil._psutil_posix' extension 15:40: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 15:40:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:40:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:40:51 INFO - ^ 15:40:51 INFO - 1 warning generated. 15:40: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 15:40:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:40:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:40:51 INFO - Successfully installed psutil 15:40:51 INFO - Cleaning up... 15:40:52 INFO - Return code: 0 15:40:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:40:52 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')]} 15:40:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:52 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')]} 15:40:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:52 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 0x1075baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98dbd99860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10745ed30>, '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 0x107282db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107455b20>, '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.vIG2C8zznB/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.Em5W69yZWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 15:40:52 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 15:40:52 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 15:40:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vIG2C8zznB/Render', 15:40:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:52 INFO - 'HOME': '/Users/cltbld', 15:40:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:52 INFO - 'LOGNAME': 'cltbld', 15:40:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:52 INFO - 'MOZ_NO_REMOTE': '1', 15:40:52 INFO - 'NO_EM_RESTART': '1', 15:40:52 INFO - 'PAGER': '/bin/cat', 15:40:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:52 INFO - 'PWD': '/builds/slave/test', 15:40:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:52 INFO - 'SHELL': '/bin/bash', 15:40:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Em5W69yZWc/Listeners', 15:40:52 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 15:40:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:52 INFO - 'USER': 'cltbld', 15:40:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:52 INFO - 'XPC_FLAGS': '0x0', 15:40:52 INFO - 'XPC_SERVICE_NAME': '0', 15:40:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:40: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 15:40: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 15:40: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 15:40: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 15:40: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 15:40: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 15:40:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:40:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:40:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:40:54 INFO - Installing collected packages: mozsystemmonitor 15:40:54 INFO - Running setup.py install for mozsystemmonitor 15:40:54 INFO - Successfully installed mozsystemmonitor 15:40:54 INFO - Cleaning up... 15:40:55 INFO - Return code: 0 15:40:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:40:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1075baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98dbd99860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10745ed30>, '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 0x107282db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107455b20>, '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.vIG2C8zznB/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.Em5W69yZWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 15:40:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:40:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vIG2C8zznB/Render', 15:40:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:55 INFO - 'HOME': '/Users/cltbld', 15:40:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:55 INFO - 'LOGNAME': 'cltbld', 15:40:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:55 INFO - 'MOZ_NO_REMOTE': '1', 15:40:55 INFO - 'NO_EM_RESTART': '1', 15:40:55 INFO - 'PAGER': '/bin/cat', 15:40:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:55 INFO - 'PWD': '/builds/slave/test', 15:40:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:55 INFO - 'SHELL': '/bin/bash', 15:40:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Em5W69yZWc/Listeners', 15:40:55 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 15:40:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:55 INFO - 'USER': 'cltbld', 15:40:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:55 INFO - 'XPC_FLAGS': '0x0', 15:40:55 INFO - 'XPC_SERVICE_NAME': '0', 15:40:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:40:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:40:55 INFO - Downloading/unpacking blobuploader==1.2.4 15:40: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 15:40: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 15:40: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 15:40: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 15:40: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 15:40: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 15:40:57 INFO - Downloading blobuploader-1.2.4.tar.gz 15:40:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:40:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:40: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 15:40: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 15:40: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 15:40: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 15:40: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 15:40: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 15:40:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:40:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:40: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 15:40: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 15:40: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 15:40: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 15:40: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 15:40: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 15:40:58 INFO - Downloading docopt-0.6.1.tar.gz 15:40:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:40:59 INFO - Installing collected packages: blobuploader, requests, docopt 15:40:59 INFO - Running setup.py install for blobuploader 15:40:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:40:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:40:59 INFO - Running setup.py install for requests 15:40:59 INFO - Running setup.py install for docopt 15:40:59 INFO - Successfully installed blobuploader requests docopt 15:40:59 INFO - Cleaning up... 15:40:59 INFO - Return code: 0 15:40:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:40: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')]} 15:40:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:40:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40: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')]} 15:40:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:40:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:40: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 0x1075baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98dbd99860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10745ed30>, '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 0x107282db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107455b20>, '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.vIG2C8zznB/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.Em5W69yZWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 15:40: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 15:40: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 15:40:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vIG2C8zznB/Render', 15:40:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:40:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:40:59 INFO - 'HOME': '/Users/cltbld', 15:40:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:40:59 INFO - 'LOGNAME': 'cltbld', 15:40:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:40:59 INFO - 'MOZ_NO_REMOTE': '1', 15:40:59 INFO - 'NO_EM_RESTART': '1', 15:40:59 INFO - 'PAGER': '/bin/cat', 15:40:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:40:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:40:59 INFO - 'PWD': '/builds/slave/test', 15:40:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:40:59 INFO - 'SHELL': '/bin/bash', 15:40:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Em5W69yZWc/Listeners', 15:40:59 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 15:40:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:40:59 INFO - 'USER': 'cltbld', 15:40:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:40:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:40:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:40:59 INFO - 'XPC_FLAGS': '0x0', 15:40:59 INFO - 'XPC_SERVICE_NAME': '0', 15:40:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:41:00 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-zHNsqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:41:00 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-HoHzL6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:41:00 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Gu7XdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:41:00 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-S5dpoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:41:00 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-N1CRj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:41:00 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-5OuRKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:41:00 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ktSXSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:41:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:41:00 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-r3vi_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:41:01 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-zjXOrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:41:01 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-UovFk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:41:01 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-CPjhPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:41:01 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-iSED_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:41:01 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-G_XPIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:41:01 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-1z0c3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:41:01 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-a_WW5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:41:01 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ckg6nZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:41:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:41:01 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ae1DCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:41:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:41:01 INFO - Running setup.py install for manifestparser 15:41:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:41:02 INFO - Running setup.py install for mozcrash 15:41:02 INFO - Running setup.py install for mozdebug 15:41:02 INFO - Running setup.py install for mozdevice 15:41:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:41:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:41:02 INFO - Running setup.py install for mozfile 15:41:02 INFO - Running setup.py install for mozhttpd 15:41:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:41:02 INFO - Running setup.py install for mozinfo 15:41:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:41:02 INFO - Running setup.py install for mozInstall 15:41:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:41:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:41:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:41:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:41:03 INFO - Running setup.py install for mozleak 15:41:03 INFO - Running setup.py install for mozlog 15:41:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:41:03 INFO - Running setup.py install for moznetwork 15:41:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:41:03 INFO - Running setup.py install for mozprocess 15:41:03 INFO - Running setup.py install for mozprofile 15:41:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:41:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:41:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:41:03 INFO - Running setup.py install for mozrunner 15:41:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:41:04 INFO - Running setup.py install for mozscreenshot 15:41:04 INFO - Running setup.py install for moztest 15:41:04 INFO - Running setup.py install for mozversion 15:41:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:41:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:41:04 INFO - Cleaning up... 15:41:04 INFO - Return code: 0 15:41:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:41: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')]} 15:41:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41: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')]} 15:41:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41: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 0x1075baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98dbd99860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10745ed30>, '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 0x107282db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107455b20>, '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.vIG2C8zznB/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.Em5W69yZWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 15:41: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 15:41: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 15:41:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vIG2C8zznB/Render', 15:41:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:04 INFO - 'HOME': '/Users/cltbld', 15:41:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:04 INFO - 'LOGNAME': 'cltbld', 15:41:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:04 INFO - 'MOZ_NO_REMOTE': '1', 15:41:04 INFO - 'NO_EM_RESTART': '1', 15:41:04 INFO - 'PAGER': '/bin/cat', 15:41:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:04 INFO - 'PWD': '/builds/slave/test', 15:41:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:04 INFO - 'SHELL': '/bin/bash', 15:41:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Em5W69yZWc/Listeners', 15:41:04 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 15:41:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:04 INFO - 'USER': 'cltbld', 15:41:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:04 INFO - 'XPC_FLAGS': '0x0', 15:41:04 INFO - 'XPC_SERVICE_NAME': '0', 15:41:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:41:04 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-y887v6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:41: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)) 15:41:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:41:04 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Ks9675-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:41:05 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)) 15:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:41:05 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-e9kvNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:41:05 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)) 15:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:41:05 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-9j9IZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:41:05 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)) 15:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:41:05 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-izS0Iy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:41: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)) 15:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:41:05 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-yWKC9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:41: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)) 15:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:41:05 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-auQKcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:41: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)) 15:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:41:05 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-oOrn1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:41: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)) 15:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:41:05 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-aqCjo4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:41: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)) 15:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:41:05 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-TEawYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:41: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)) 15:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:41:05 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-5jvmED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:41: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)) 15:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:41:05 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-q0LRmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:41:06 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)) 15:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:41:06 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-jW8p8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:41:06 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)) 15:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:41:06 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-XwpKdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:41: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)) 15:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:41:06 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-_EPvuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:41: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)) 15:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:41:06 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-gFUk_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:41: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)) 15:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:41:06 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ffZpSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:41: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)) 15:41: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)) 15:41: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)) 15:41: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)) 15:41: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)) 15:41: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)) 15:41:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:41: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 15:41: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 15:41: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 15:41: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 15:41: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 15:41: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 15:41:08 INFO - Downloading blessings-1.6.tar.gz 15:41:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:41:09 INFO - Installing collected packages: blessings 15:41:09 INFO - Running setup.py install for blessings 15:41:09 INFO - Successfully installed blessings 15:41:09 INFO - Cleaning up... 15:41:09 INFO - Return code: 0 15:41:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:41:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:41:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:41:09 INFO - Reading from file tmpfile_stdout 15:41:09 INFO - Current package versions: 15:41:09 INFO - blessings == 1.6 15:41:09 INFO - blobuploader == 1.2.4 15:41:09 INFO - docopt == 0.6.1 15:41:09 INFO - manifestparser == 1.1 15:41:09 INFO - mozInstall == 1.12 15:41:09 INFO - mozcrash == 0.17 15:41:09 INFO - mozdebug == 0.1 15:41:09 INFO - mozdevice == 0.48 15:41:09 INFO - mozfile == 1.2 15:41:09 INFO - mozhttpd == 0.7 15:41:09 INFO - mozinfo == 0.9 15:41:09 INFO - mozleak == 0.1 15:41:09 INFO - mozlog == 3.2 15:41:09 INFO - moznetwork == 0.27 15:41:09 INFO - mozprocess == 0.22 15:41:09 INFO - mozprofile == 0.28 15:41:09 INFO - mozrunner == 6.11 15:41:09 INFO - mozscreenshot == 0.1 15:41:09 INFO - mozsystemmonitor == 0.3 15:41:09 INFO - moztest == 0.7 15:41:09 INFO - mozversion == 1.4 15:41:09 INFO - psutil == 3.1.1 15:41:09 INFO - requests == 1.2.3 15:41:09 INFO - wsgiref == 0.1.2 15:41:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:41: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')]} 15:41:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41: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')]} 15:41:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41: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 0x1075baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98dbd99860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10745ed30>, '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 0x107282db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107455b20>, '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.vIG2C8zznB/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.Em5W69yZWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 15:41: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 15:41: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 15:41:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vIG2C8zznB/Render', 15:41:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:09 INFO - 'HOME': '/Users/cltbld', 15:41:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:09 INFO - 'LOGNAME': 'cltbld', 15:41:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:09 INFO - 'MOZ_NO_REMOTE': '1', 15:41:09 INFO - 'NO_EM_RESTART': '1', 15:41:09 INFO - 'PAGER': '/bin/cat', 15:41:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:09 INFO - 'PWD': '/builds/slave/test', 15:41:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:09 INFO - 'SHELL': '/bin/bash', 15:41:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Em5W69yZWc/Listeners', 15:41:09 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 15:41:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:09 INFO - 'USER': 'cltbld', 15:41:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:09 INFO - 'XPC_FLAGS': '0x0', 15:41:09 INFO - 'XPC_SERVICE_NAME': '0', 15:41:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41: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)) 15:41: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)) 15:41: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)) 15:41: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)) 15:41: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)) 15:41: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)) 15:41: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)) 15:41: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)) 15:41: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)) 15:41: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)) 15:41: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)) 15:41:09 INFO - Cleaning up... 15:41:09 INFO - Return code: 0 15:41:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:41: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')]} 15:41:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41: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')]} 15:41:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41: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 0x1075baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98dbd99860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10745ed30>, '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 0x107282db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107455b20>, '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.vIG2C8zznB/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.Em5W69yZWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 15:41: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 15:41: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 15:41:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vIG2C8zznB/Render', 15:41:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:09 INFO - 'HOME': '/Users/cltbld', 15:41:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:09 INFO - 'LOGNAME': 'cltbld', 15:41:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:09 INFO - 'MOZ_NO_REMOTE': '1', 15:41:09 INFO - 'NO_EM_RESTART': '1', 15:41:09 INFO - 'PAGER': '/bin/cat', 15:41:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:09 INFO - 'PWD': '/builds/slave/test', 15:41:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:09 INFO - 'SHELL': '/bin/bash', 15:41:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Em5W69yZWc/Listeners', 15:41:09 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 15:41:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:09 INFO - 'USER': 'cltbld', 15:41:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:09 INFO - 'XPC_FLAGS': '0x0', 15:41:09 INFO - 'XPC_SERVICE_NAME': '0', 15:41:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:10 INFO - Downloading/unpacking jsonschema 15:41:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:41:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:41:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:41:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:41:12 INFO - Downloading/unpacking functools32 (from jsonschema) 15:41: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 15:41: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 15:41: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 15:41: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 15:41: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 15:41: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 15:41:12 INFO - Downloading functools32-3.2.3-2.tar.gz 15:41:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:41:13 INFO - warning: no files found matching '*.txt' 15:41:13 INFO - no previously-included directories found matching 'build' 15:41:13 INFO - no previously-included directories found matching 'dist' 15:41:13 INFO - no previously-included directories found matching '.git*' 15:41:13 INFO - Installing collected packages: jsonschema, functools32 15:41:13 INFO - Running setup.py install for functools32 15:41:13 INFO - warning: no files found matching '*.txt' 15:41:13 INFO - no previously-included directories found matching 'build' 15:41:13 INFO - no previously-included directories found matching 'dist' 15:41:13 INFO - no previously-included directories found matching '.git*' 15:41:13 INFO - Successfully installed jsonschema functools32 15:41:13 INFO - Cleaning up... 15:41:13 INFO - Return code: 0 15:41:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:41: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')]} 15:41:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:41:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41: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')]} 15:41:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:41:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:41: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 0x1075baa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1075c0570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98dbd99860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10745ed30>, '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 0x107282db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107455b20>, '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.vIG2C8zznB/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.Em5W69yZWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 15:41: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 15:41: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 15:41:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vIG2C8zznB/Render', 15:41:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:13 INFO - 'HOME': '/Users/cltbld', 15:41:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:13 INFO - 'LOGNAME': 'cltbld', 15:41:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:13 INFO - 'MOZ_NO_REMOTE': '1', 15:41:13 INFO - 'NO_EM_RESTART': '1', 15:41:13 INFO - 'PAGER': '/bin/cat', 15:41:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:13 INFO - 'PWD': '/builds/slave/test', 15:41:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:13 INFO - 'SHELL': '/bin/bash', 15:41:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Em5W69yZWc/Listeners', 15:41:13 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 15:41:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:13 INFO - 'USER': 'cltbld', 15:41:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:13 INFO - 'XPC_FLAGS': '0x0', 15:41:13 INFO - 'XPC_SERVICE_NAME': '0', 15:41:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:41:13 INFO - Downloading/unpacking flake8 15:41: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 15:41: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 15:41: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 15:41: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 15:41: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 15:41: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 15:41:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:41:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:41: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 15:41: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 15:41: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 15:41: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 15:41: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 15:41: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 15:41:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:41:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:41: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 15:41: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 15:41: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 15:41: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 15:41: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 15:41: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 15:41:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:41: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 15:41: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 15:41: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 15:41: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 15:41: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 15:41: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 15:41:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:41:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:41:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:41:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:41:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:41:17 INFO - no previously-included directories found matching 'docs/_build' 15:41:17 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:41:17 INFO - Running setup.py install for pep8 15:41:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:41:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:41:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:41:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:41:17 INFO - no previously-included directories found matching 'docs/_build' 15:41:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:41:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:41:17 INFO - Cleaning up... 15:41:17 INFO - Return code: 0 15:41:17 INFO - Running post-action listener: _resource_record_post_action 15:41:17 INFO - Running post-action listener: _start_resource_monitoring 15:41:17 INFO - Starting resource monitoring. 15:41:17 INFO - ##### 15:41:17 INFO - ##### Finished create-virtualenv step (success) 15:41:17 INFO - ##### 15:41:17 INFO - ##### 15:41:17 INFO - ##### Running install step. 15:41:17 INFO - ##### 15:41:17 INFO - Running pre-action listener: _resource_record_pre_action 15:41:17 INFO - Running main action method: install 15:41:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:41:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:41:17 INFO - Reading from file tmpfile_stdout 15:41:17 INFO - Detecting whether we're running mozinstall >=1.0... 15:41:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:41:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:41:18 INFO - Reading from file tmpfile_stdout 15:41:18 INFO - Output received: 15:41:18 INFO - Usage: mozinstall [options] installer 15:41:18 INFO - Options: 15:41:18 INFO - -h, --help show this help message and exit 15:41:18 INFO - -d DEST, --destination=DEST 15:41:18 INFO - Directory to install application into. [default: 15:41:18 INFO - "/builds/slave/test"] 15:41:18 INFO - --app=APP Application being installed. [default: firefox] 15:41:18 INFO - mkdir: /builds/slave/test/build/application 15:41:18 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'] 15:41:18 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 15:41:52 INFO - Reading from file tmpfile_stdout 15:41:52 INFO - Output received: 15:41:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:41:52 INFO - Running post-action listener: _resource_record_post_action 15:41:52 INFO - ##### 15:41:52 INFO - ##### Finished install step (success) 15:41:52 INFO - ##### 15:41:52 INFO - ##### 15:41:52 INFO - ##### Running run-tests step. 15:41:52 INFO - ##### 15:41:52 INFO - Running pre-action listener: _resource_record_pre_action 15:41:52 INFO - Running main action method: run_tests 15:41:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:41:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:41:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:41:52 INFO - 2016-05-25 15:41:52.421 screenresolution[1638:7407] starting screenresolution argv=screenresolution get 15:41:52 INFO - 2016-05-25 15:41:52.424 screenresolution[1638:7407] Display 0: 1600x1200x32@60 15:41:52 INFO - 2016-05-25 15:41:52.429 screenresolution[1639:7409] starting screenresolution argv=screenresolution list 15:41:52 INFO - Available Modes on Display 0 15:41:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:41:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:41:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:41:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:41:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:41:52 INFO - Intel Iris: 15:41:52 INFO - Chipset Model: Intel Iris 15:41:52 INFO - Type: GPU 15:41:52 INFO - Bus: Built-In 15:41:52 INFO - VRAM (Dynamic, Max): 1536 MB 15:41:52 INFO - Vendor: Intel (0x8086) 15:41:52 INFO - Device ID: 0x0a2e 15:41:52 INFO - Revision ID: 0x0009 15:41:52 INFO - Displays: 15:41:52 INFO - Display: 15:41:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:41:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:41:52 INFO - Display Serial Number: 1600x1200 60 15:41:52 INFO - Main Display: Yes 15:41:52 INFO - Mirror: Off 15:41:52 INFO - Online: Yes 15:41:52 INFO - Rotation: Supported 15:41:52 INFO - Return code: 0 15:41:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:41:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:41:52 INFO - Python 2.7.3 15:41:52 INFO - Return code: 0 15:41:52 INFO - grabbing minidump binary from tooltool 15:41:52 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')]} 15:41:52 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 0x10745ed30>, '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 0x107282db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107455b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:41:52 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 15:41:52 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 15:41:52 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 15:41:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:41:52 INFO - Return code: 0 15:41:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:41:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:41:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:41:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:41:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:41:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:41:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:41:53 INFO - Return code: 0 15:41: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/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--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 15:41: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/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0127 --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 15:41:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vIG2C8zznB/Render', 15:41:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:41:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:41:53 INFO - 'HOME': '/Users/cltbld', 15:41:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:41:53 INFO - 'LOGNAME': 'cltbld', 15:41:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:41:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:41:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:41:53 INFO - 'MOZ_NO_REMOTE': '1', 15:41:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:41:53 INFO - 'NO_EM_RESTART': '1', 15:41:53 INFO - 'PAGER': '/bin/cat', 15:41:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:41:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:41:53 INFO - 'PWD': '/builds/slave/test', 15:41:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:41:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:41:53 INFO - 'SHELL': '/bin/bash', 15:41:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Em5W69yZWc/Listeners', 15:41:53 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 15:41:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:41:53 INFO - 'USER': 'cltbld', 15:41:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:41:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:41:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:41:53 INFO - 'XPC_FLAGS': '0x0', 15:41:53 INFO - 'XPC_SERVICE_NAME': '0', 15:41:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:41: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/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--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 15:41:54 INFO - mozversion application_buildid: 20160525135343 15:41:54 INFO - mozversion application_changeset: 3531b8206f7051e2286f860e2c449bb5ff7f4a4c 15:41:54 INFO - mozversion application_display_name: Nightly 15:41:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:41:54 INFO - mozversion application_name: Firefox 15:41:54 INFO - mozversion application_remotingname: firefox 15:41:54 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:41:54 INFO - mozversion application_vendor: Mozilla 15:41:54 INFO - mozversion application_version: 49.0a1 15:41:54 INFO - mozversion platform_buildid: 20160525135343 15:41:54 INFO - mozversion platform_changeset: 3531b8206f7051e2286f860e2c449bb5ff7f4a4c 15:41:54 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:41:54 INFO - mozversion platform_version: 49.0a1 15:41:54 INFO - using testdate: 1464216114 15:41:54 INFO - actual date: 1464216114 15:41:54 INFO - starting webserver on 'localhost:49271' 15:41:54 INFO - SUITE-START | Running 2 tests 15:41:54 INFO - TEST-START | dromaeo_css 15:41:54 INFO - operating with platform_type : mac_ 15:41:54 INFO - Initialising browser for dromaeo_css test... 15:41:54 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpIttZKy/profile http://localhost:49271/getInfo.html) 15:41:56 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 15:41:56 INFO - PROCESS | 1657 | colorDepth:24 15:41:56 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 15:41:56 INFO - PROCESS | 1657 | __metrics 15:41:56 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" 15:41:56 ERROR - PROCESS | 1657 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:41:57 INFO - TEST-INFO | 1657: exit 0 15:41:57 INFO - Browser initialized. 15:41:57 INFO - Running cycle 1/1 for dromaeo_css test... 15:41:57 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpIttZKy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:42:09 INFO - PROCESS | 1659 | RSS: Main: 241168384 15:42:09 INFO - PROCESS | 1659 | 15:43:00 INFO - PROCESS | 1659 | 1464216180215 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] 15:45:11 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 15:45:12 INFO - PROCESS | 1659 | RSS: Main: 370966528 15:45:12 INFO - PROCESS | 1659 | 15:48:15 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 15:48:16 INFO - PROCESS | 1659 | RSS: Main: 379408384 15:48:16 INFO - PROCESS | 1659 | 15:51:18 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 15:51:18 INFO - PROCESS | 1659 | RSS: Main: 371879936 15:51:18 INFO - PROCESS | 1659 | 15:54:21 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 15:54:21 INFO - PROCESS | 1659 | RSS: Main: 371220480 15:54:21 INFO - PROCESS | 1659 | 15:57:25 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 15:57:25 INFO - PROCESS | 1659 | RSS: Main: 332943360 15:57:25 INFO - PROCESS | 1659 | 16:00:28 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 16:00:29 INFO - PROCESS | 1659 | RSS: Main: 377196544 16:00:29 INFO - PROCESS | 1659 | 16:00:29 INFO - PROCESS | 1659 | __start_tp_report 16:00:29 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 16:00:29 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 16:00:29 INFO - PROCESS | 1659 | |i|pagename|runs| 16:00:29 INFO - PROCESS | 1659 | |0;dojo.html;3441.1741182116293;3459.927422499024;3483.7038851697603;3486.2853115111407;3529.48822420749;5618.4662457066615;5644;5671.6597004179675;5700.401457846923;5736.1682556029355;5595.8601034974145;5682.857928551788;5693.572982026344;5702.4012478689765;5723.713814309289;5102.234664800272;5141.614378921578;5208.609354298424;5224.45143259958;5318.335208098988;5323.574114070865;5327.200919862013;5354.812581559646;5371.865703357408;5412.702301373423;4290.549492303295;5136.43499215086;5189.558887494568;5241.344831896013;5247.606429517801;5244.842654720364;5251.212318152269;5254.605904557174;5264.341957255343;5269.367675878919;5154.845354639329;5175.223716442526;5217.191335343028;5286.20706893969;5298.099323115041;5564.221009058694;5604.243427137325;5622.072358060915;5680.147977803321;5692.5161361284345;5155.278261043457;6226.906596401012;6247.468965137969;6252.6561039142825;6253.437190652863;6122.755089796403;6131.325554189036;6137.622145771035;6218.782342617987;6224.221972253468;5875.1187321901625;5889.764409423661;5891.617044892068;5901.144334071536;5919.792807251769;6258.499320054385;6261.029540421151;6269.247690277196;6273.623582584968;6278.560500764903;6031.366706495843;6110.205673262448;6139.201903752483;6146.446819786241;6193;6199.907001394983;6226.533010024266;6231.781887633911;6235.781747638811;6236.501079913596;6073.180120683651;6191.84520386994;6217.222847144107;6218.409251121137;6231.096491008779;6115.115075256843;6116.853194528077;6180.134781130645;6184.010558310649;6267.341929097471;4836.830710925171;4838.517577465868;4877;4904.558589726942;4928.605711543068;4311.137772445523;4451.443569553806;4461.152381047591;4481.462224533077;4512.2329204035395;5540.473655002768;5611.2144299798065;5613.354464236665;5613.775448982076;5620.241267428844;6128.625347140816;6130.153553065287;6174.228221472316;6202.534809889276;6293.244810622755;5750.079987202028;5759.049756790083;5761;5772.5959182856795;5788.565857560699;5344.305240318734;5357.276767636397;5377.489138531833;5384.865378365572;5386.245925570423;5226.268322434862;6219.40915613016;6226.066090086523;6277.466415354746;6292.339304373067;6284.340144284967;6302.810915672537;6309.7160627770945;6309.810705678837;6328.936710633019;6254.562180647311;6328.303886572381;6328.841778955563;6331.556791024584;6338.778142764981;5580.693061881636;6000.669963152069;6029.427204415573;6122.367660829284;6127.006280530498;6275;6282.026285925689;6282.277538083179;6291.087792270004;6296.779612713533;5901.203337549376;6110.824435359022;6140.815775526741;6141.056449558148;6148.1085242640465;6045.274567051982;6084.817455476343;6110.877782444238;6141.6007959482495;6157.922363586444;5243.790248390174;5305.283786688902;5560.499555040065;5934.792282270272;5994.730237139252;5983.640981541122;5989.221401217814;6129.601575897553;6140.232470941132;6151.446369826737;5837.445442682938;5902.881942361043;5916.881662366814;5921.99326114553;5954.910676339771 16:00:29 INFO - PROCESS | 1659 | |1;ext.html;505.46925727985627;515.1757188498403;517.4411635433732;543.5325619966831;547.1956224350205;30185.094447166593;31014.534781978287;31872;31875.203119922015;31930;42251.78874105633;43511.564884351144;43627.90930226738;46252.53747462524;46303.305450418186;9514.571844267024;9671.854922176173;9678.274129440286;9685.951570242174;9692.079252471003;8459.323254139654;8683.13168683133;9475.099865512766;9594.184494317957;9610.567524461416;11477.909598588121;11730.002949749281;11749.706257343549;12046.15676902617;12052.397380131002;11597.188196826226;11717.589884354007;11725.355105469238;11727.941360293229;11733.647990560297;8447.070822209553;11295.265807722471;11488.827667584992;11656.708582285426;11806.645800625995;38931.61068389336;39266.60733392658;39270.60729392698;39340;39347.21305573901;3126.3278395145153;3177.9205519861957;3235.8382080895863;3408.3694516514274;3803.4294855771855;27802.582961255393;28292.858535707397;28486.430271394456;28791.85604071987;29098.92641844842;2677.8259413138085;2702.5405681034076;2719.7960152988603;2749.0653177919403;2831.2214141111153;501719;502851;503216.99999999633;503979;504638;57606.42393576095;58490.70754646199;58493.4150658488;58501;58519.70740146272;62048.68975655093;62116;62116.689416552625;62171.756564869356;62333.68833155805;16309.347626094823;16803.49589512292;16827.41104061352;16830.990140591603;16832.831671683125;13065.673358166121;13098;13104.410301536454;13138.343082845819;13149.737005260033;13651.317434128254;14007;15279.541613751606;15514.767278490832;15536.83473739474;5603.5237004854225;6238.1890354254565;6692.163479565054;6736.562123461959;6752.966235168891;695.085961960021;945.2579724915907;1024.6379761414262;1049.5277125293649;1076.1229597877705;27746.890124395202;28382.124197343805;28972.985945491808;29442.852785736362;29662.55506167409;1103.8233882578909;1105.165599972016;1108.141190577309;1109;1146.4153281826161;14913.70172596542;14923.477678281208;15009.549713508612;15319.846801532065;15364.231788410758;673547;678337;678716;678737;680056;59549.70225148847;59604;59638;59679.70160149172;59689.80620387655;1317.2096741954942;1321.1280554833957;1335.7194989052407;1430.5565274764856;1478.9852101479062;28608.284792879513;29418.852905735333;29752;29913.85043074771;30814.845925770227;57861.4213857856;58202.83594328005;58638.70680646569;58672.4132758667;58831;582911;588594;589796;589816;591297;25186.11848585291;25341.239762807145;26031.479370412864;26090.478190436465;26903;45681.54318456906;45913;46200.537994620965;46751.29873051975;47334;36304.91085267446;36309.09827173404;37545.81227093847;38037.80981095077;38067.42898856464;36286.729964451115;36831.132731062855;37221.813890930374;37662.841345292654;38199.61800382072;36588.45117323189;36899.63100368962;36908.44637330389;37612.62387376201;37756.43365349467;31901.20246993844;33169;33363.66636333606;33382;33396.332073358884;20595.794042059388;22852.657210142195;23885.044598215878;24192.87903560471;24832.875835620707 16:00:29 INFO - PROCESS | 1659 | |2;jquery.html;390.56061930328383;390.8631978807418;392.2860394082767;396.31833246815484;417.3989455184534;20027.499312517175;20282.290119845773;21253.57492850142;21335.67996480054;21353.145874165035;20039.897805620683;20178.192872285126;21008.579828403424;21088.261910833124;21092.683609745865;5041.470645582216;5398.541124004464;5410.404855465896;5423.159410291411;5425.593080518957;5249.343832020997;5264.078786212397;5358.115910874701;5363.436839131893;5367.75845086972;5220.817271395502;5385.78456861725;5409.729513524328;5411.621186516945;5417.349918009844;4485.641148708128;5311.282976798141;5325.893482130375;5355.812546560871;5707.286589176353;5874.73563689635;5877.735501902382;5894.263217097863;5901.704914754245;5912.320083190402;13505.514393416715;13508;13559.59321220365;13860.099093558987;13885.097468664504;1958.3439547751377;1986.2055177928798;2224.477247846755;2370.4310965368186;2674.665666791651;9615.615375384976;9712.368696034735;9776.364536305118;10022.3485473444;10191.745206369826;1559.8674112700435;1575.4328441760956;1716.648087142133;1791.7491551182845;1805.2147315917487;116949.24576131365;118530.40734796357;121510.98451201171;121994;124888.37555812254;9442.952785236168;11404.144689148347;11548.538058477567;11588.768224635629;11686.88313116875;14614;14754.483593074405;14758;14760.040597361138;14764.26178691061;8603.483790972561;8794.296456283482;8815.779605509913;8907.777305567282;8914.420562663407;7020.333068358498;8825.69110081144;8826.205641492297;8919.241864441465;8944.50805205707;4502.774861256924;4605.700629459075;4610.76946152691;4644.58198762113;4683.110209060268;1736.9913150434168;1894.1665667106429;1899.069455966594;1957.4225603447017;1958.099274333794;831.08580561382;913.6665117232162;941.1435593609782;962.3888830592624;1045.8431235314763;19447.3193438229;20262.797372026387;20392.59214815725;20449.591008179752;20600.48498787542;908.5457271364318;909.1544863277084;944.617429940875;954.8281309364381;1019;8337.708180213785;10697.518611662494;10716.303440276357;10764.246502744732;10781;600356;606414;616807;618392;624555;89561;103509.96490035154;104214.95785042053;132189;136674;1008.2085562833141;1008.7831116309882;1030.99484502577;1032.1175395037135;1034.363866222268;14625.85374146245;17190.484285471455;17646.91176544109;17666.293348265925;18112.366067187584;39865;52588.9482210346;52790.47209528009;55682.88634227374;56754.14868776889;75312.24687753271;75425.6228718853;76003;76266;78381.82427263481;6872.244053154249;7264.636768161465;7324.926750732425;7365.079365079365;8258.132896045829;9005.961651010432;12270.386480675752;12355.899497085336;12690.302033387883;12757.744845103232;5461.972690136524;6916.515843890879;10709.250352475563;10851.945740271249;11082.783443311531;9591.376560523544;10270.127039201452;10886.455677215949;11060;11230.04544613717;4578.473475550221;6515.446187073962;8870.822583548423;11165.330080195214;11398.575178102737;7223.458240631869;7309.890351644836;7381.852362952818;7386.335229829127;7624.923750762421;8226.341892648454;10542.57829686804;10909.181811364339;11203.99164075237;11235.101191904465 16:00:29 INFO - PROCESS | 1659 | |3;mootools.html;1412.4420853762758;1527.503561342564;1558.4623304959791;1562.7265228585002;1981.6036792641466;7745;8471.703490377839;8671;8671.046184919654;8808.339374546918;8408.579571021455;8559.186877246651;8653.524056176908;8661.956690216555;8667.523286219272;2059.557195203031;2128.0423809285808;2132.648113061343;2293.334932869466;2334.9533009339802;2047.170895787201;2073.139647046474;2284.520250747337;2342.484653376263;2345.1205797825814;2325.9767402326015;2334;2338.555674421863;2338.5790557699606;2338.684277622516;2041.6018876319124;2285.3029825903095;2301.2520930697506;2324.8023917966993;2329.033451117784;2025.473376921996;2279.951222437681;2345.0502546468706;2345.906163753448;2361.5276944611146;8574.314054875595;8635.568221588957;8645.221930026328;8653.307735381155;8676.045634980148;5139.8458046258675;5294.126469132589;5306.575473962073;5311.521963023308;5324.41431442541;3476.582810062783;3496.6328535503662;3501.77238479498;3584.8386822593047;3588.3540962626716;971.7182017214999;1009.9495025248708;1014.178515402519;1014.5181039006486;1022.3610243597751;9701.805963880683;11537.94231028848;11565.132615053904;11571.247868888495;11595.826062609067;3374.7806392584403;4623.514530974267;4725.598324142417;4786.353842231324;4796.088743138793;9701.320907536547;9719.31964762474;9723.222142228604;9725.854112188323;9739.123478886933;5215.47845215483;5278;5278.155495120763;5286.20706893969;5286.9471305287225;4701;4801.831935882227;4869.001854619865;4886.804527818918;4893.241547560134;3896.3376226041073;3919.1573811630637;3930.292547341506;3969.861054863066;4035.6771458283265;1259.80473026681;1281.5834853672593;1285.8135570342436;1296.8962483001337;1298.3832679477362;635.5678138865524;694.8297667071599;825.7481468152271;907.9273658107336;949.715085474355;5281.9207711884355;5326.733663316819;5331.173668081454;5414.187871819258;5488.17677348401;985.9014098590227;1002.9899701003043;1009.9192064634811;1022.4070039377292;1022.5909636145601;3837.865674701372;3915;3972.4835771350117;3992.8203230854683;4005.639492445694;10035.498225088862;10613.84079238812;10626.203034772423;10628.09661178793;10678.985496377833;10054.34646747959;10446.164306855586;10576.31253968505;10590.947045264878;10634.255602107778;7875.488093273919;8181;8203.179682031749;8208.343332533503;8208.83582328362;5712.400197979318;5825.184474173704;5852.707364631837;5920.496757775637;5948;9807.509624518889;9876.962918893412;9938.950305248427;9947.651832185838;9952.353097048668;7723.652435640522;9545.61817527319;9557.092076252746;10091.243156763125;10101.69694909154;7366.96316518414;7489.588072656056;7568.962155189189;7576;7582.848343032998;8010.3992200584025;8052.315553178046;8101.230383113595;8101.635426405944;8162.510249385056;7369.631518424165;7411.740589079356;7420.109586849612;7544.283293087148;7602.315791578784;7063.258357872555;7284;7284.562926224232;7315.414766818748;7444.739434119996;6881.380675739208;6911.481638877004;6917.827054323682;6991.161060672752;6997.510174287954;3974.3641017438226;4512.864614061584;4845.878853028703;4855.514448555116;4896.779644915916;4249.617534421917;4764.690295130806;4808.663393562557;4811.542903424169;4847.691123396734 16:00:29 INFO - PROCESS | 1659 | |4;prototype.html;298.92228020714623;306.4606292924453;316.58685415532733;318.6351627386644;320.56563356651736;4638.026014536943;4672.766361681911;4909.895273563447;5271.288376069234;5291.2327712481665;5017.42299635542;5024.874378140548;5162.380514338284;5207.375114986207;5252.553532949704;3417.820851806135;3649.9270014599692;4080.1227736036744;4091.338252264222;4104.630583247515;4091.3610721084883;4107.404426358176;4117.8764637060785;4118.629323360912;4131.979340103296;2917.53319468885;3182.76129290303;3676.7977761223124;4082.6733861291173;4105.712600117993;3145.779795414322;3707.925841483169;4040.9191816163807;4068.9186216275803;4082.4488694026372;2977.5384815353655;3544.8404821782824;3554.4940816724147;3582.707067476817;3595.7662751921043;3983.223271462066;4093.5087789465456;4640.814367425332;5115.923261151086;5123.462036486189;3842.884713458601;4489.842855500042;4544;4562.946673996715;4571.314302854598;4649.349091127245;4768.7854046567645;5539.307586551681;5550.86122846928;5567.526760225385;3384.7461440392067;3713.3687273163628;3803.5435747710176;3815.332316844569;3834.2523207974455;5127.87180320495;5173.870653233662;5817.534597232254;5855.443732845373;5889.322727886348;3036.073997430805;3706.6849317808287;3786.659200671953;3859.6333348331864;3862.1503269280715;3606.765560238576;3807.2195199983935;3920.6863450923856;3928.8821335359985;3943.4873466449176;3984.62530427008;4292.48490181174;4543.318502224693;4548.226801443762;4556.20266453376;5098.796048158107;5401.702906340189;5493.533049690821;5512.50387465122;5533.723313834373;1208.3656080557778;1212.1514939542355;1251.192980527573;1331.227887825059;1343.9596812095654;733.8771679330632;754.5548126605329;778.5873487051872;780.5511830697371;781.9452638315263;4478.641708663299;4699.318598803155;5126.410462796752;5246.396664383568;5254.789808407697;4806.255030470283;5338.878835444601;5672.602917795714;5718.285214348207;5763.798267060716;4986.551210391082;5219.555093638181;5231.881088159302;5233.502817232356;5249.028929648028;2749.4913441013477;2833.9008134715186;3006;3035.5446682997285;3040.6503252125844;5271.947280527223;5580.637258578179;5876.853078673067;5931.228940237742;5963.2844058713235;5204.245384419238;5396.757145928443;5519.917201241985;5875.970620146872;5881.529477641779;5325.414204437431;5922.496587790086;5922.674252916045;5929;5951.642901425928;5324.307839980777;5586.77652893896;5642.210090587239;5741;5747.8563035924435;5328.920066198933;5635.802746904003;5646.971765141148;5670.9432905672065;5683.687397193194;4785.569298763128;4847.515248475124;5582.692951887685;5715.6568206472275;5911.7635294587735;3082.737967272717;3952.9209415812097;3985.5815139409942;3992.1436532011594;4020.6932746856805;5418.3497980241045;5763.077907534775;5794.62334948227;5801.360381026707;5868.23712917318;5209.270702101633;5343.385510666168;5428.945710543002;5555.1113421021655;5631.943680563306;4548;4933.963867587847;5392.299001129828;5579.553635709215;5655.981923253854;4569.680122391401;4924.310596516562;5246.291750613618;5528.474794894479;5545.390007099297;4805;4807.735574543433;4894.870731231783;5194.052230413595;5650.976727450959;5492.395836458067;5516.751746171512;5542.140968149944;5546.835164615314;5549.667019978814 16:00:29 INFO - PROCESS | 1659 | |5;yui.html;637.6747858592119;668.7358493395107;691.4813889582816;711.1182134153648;753.6118898767135;14816.555503334903;15036.473723419682;15190.620234494143;15381.000234984718;15388.69222615547;14463;14533.273336333194;14593.051451655634;14596.27018649066;14653;728.7085165933617;753.1827966656157;893.7542175901631;900.4597241654988;911.6080085563206;745.1803016681633;745.9515131516462;748.2779118150984;749.9987516665259;865.9393842431033;957.8036502516967;965.7054598347502;966.1063516247478;966.6036924860808;968.9709308720714;951.4338968313842;958.6692591056075;959.5058544849409;960.8030353777459;965.8647789309503;555.9888802223933;678.8981652752077;699.4684040129488;710.882704353781;711.5943911970128;14688;14746.557603271918;14769.187694676788;14792.778108328384;14815.777763333559;11936.522539098425;12056.397180141046;12198.573049943298;12271;12352.38238088092;10707.946460267727;10852.782944341068;10992.230543861933;11063.225574209808;11414.71463213418;4043.9595604043875;4091.7954102294766;4092.774897380643;4134.235166494208;4250.192463431969;74105;76262.47475050468;76468;76623;78826.99999999943;9214.677486288081;9280.489573073546;9299.256059515223;9621;9736.318457707892;21183.046762895705;21200;21210.469738256357;21214.25750098739;21235.68146477804;7211.495195336276;7232.963835180791;7263.418926485869;7287.526310789888;7411.184769675331;3935.8622448214173;4047.9392809107885;4091.3453847384276;4092.508898932147;4102.261592913304;3347.196672798511;3449.844756985942;3456.740744444177;3463.0995941055508;3484.198634314122;627.9590340920375;633.8415396150963;645.4449173710605;648.1060680906603;659.5185514574291;497.50746760707364;521.6270366687836;524.3026774390052;543.4029496189235;564.0242380681444;7173.569585824867;7229.819254518679;7289.671964761598;7360.263973602704;7630.847383052419;469.99060018799435;492.6576029659424;538.1846502548642;553.1039715660576;575.8646718021262;3492.301539692021;3518.82405879705;3532.6114127445962;3610.819459027038;3688.3176612326806;74417;84425.31149376978;85285.57357213175;90122.54938725395;90178.54910725405;78529.60735196287;80841;81206.59396703097;81522;82898.75651865227;66999.66500167469;67640.32359676439;73460.63269683617;74098.77703669007;74978.25021749822;6478.611283323015;6612.90080648781;6725.831854203489;6726.192856857208;7119.67961441726;44544;46477.76761116308;47372.52627473681;48979.02041959212;49124.50875491199;62532;65556.67221663862;65773.67113164403;65934.67032664806;67031.3296867025;9713.465759383302;9840.901590984286;9895.752606184902;9913.107820296207;10243.897561024593;9150;10479.68560943173;10535.051845333957;10612.371123736399;10696.518656660313;9778.07108324708;10085.092341689284;10318.329308594917;11026.228164028762;11133.276337038067;10588.152947764007;10816.4050977197;10833.458327083457;10885.510152043336;11525.135614828754;10184.745381365525;10936.507857146607;11175.72060698489;11230.213885028297;11996.820047699117;8442.873356899774;9014;9174.67888623895;9224.077592240723;9356;9322.580483878377;9470.796389805771;9493.335466517554;9554.952225238829;9699.272554558294 16:00:29 INFO - PROCESS | 1659 | __end_tp_report 16:00:29 INFO - PROCESS | 1659 | __start_cc_report 16:00:29 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1320 16:00:29 INFO - PROCESS | 1659 | __end_cc_report 16:00:29 INFO - PROCESS | 1659 | __startTimestamp1464217229103__endTimestamp 16:00:29 INFO - PROCESS | 1659 | 16:00:29 INFO - PROCESS | 1659 | ------- Summary: start ------- 16:00:29 INFO - PROCESS | 1659 | Number of tests: 6 16:00:29 INFO - PROCESS | 1659 | 16:00:29 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5713.84 Median:5902.88 stddev:626.75 (10.6%) stddev-sans-first:602.78 16:00:29 INFO - PROCESS | 1659 | Values: 3441.2 3459.9 3483.7 3486.3 3529.5 5618.5 5644.0 5671.7 5700.4 5736.2 5595.9 5682.9 5693.6 5702.4 5723.7 5102.2 5141.6 5208.6 5224.5 5318.3 5323.6 5327.2 5354.8 5371.9 5412.7 4290.5 5136.4 5189.6 5241.3 5247.6 5244.8 5251.2 5254.6 5264.3 5269.4 5154.8 5175.2 5217.2 5286.2 5298.1 5564.2 5604.2 5622.1 5680.1 5692.5 5155.3 6226.9 6247.5 6252.7 6253.4 6122.8 6131.3 6137.6 6218.8 6224.2 5875.1 5889.8 5891.6 5901.1 5919.8 6258.5 6261.0 6269.2 6273.6 6278.6 6031.4 6110.2 6139.2 6146.4 6193.0 6199.9 6226.5 6231.8 6235.8 6236.5 6073.2 6191.8 6217.2 6218.4 6231.1 6115.1 6116.9 6180.1 6184.0 6267.3 4836.8 4838.5 4877.0 4904.6 4928.6 4311.1 4451.4 4461.2 4481.5 4512.2 5540.5 5611.2 5613.4 5613.8 5620.2 6128.6 6130.2 6174.2 6202.5 6293.2 5750.1 5759.0 5761.0 5772.6 5788.6 5344.3 5357.3 5377.5 5384.9 5386.2 5226.3 6219.4 6226.1 6277.5 6292.3 6284.3 6302.8 6309.7 6309.8 6328.9 6254.6 6328.3 6328.8 6331.6 6338.8 5580.7 6000.7 6029.4 6122.4 6127.0 6275.0 6282.0 6282.3 6291.1 6296.8 5901.2 6110.8 6140.8 6141.1 6148.1 6045.3 6084.8 6110.9 6141.6 6157.9 5243.8 5305.3 5560.5 5934.8 5994.7 5983.6 5989.2 6129.6 6140.2 6151.4 5837.4 5902.9 5916.9 5922.0 5954.9 16:00:29 INFO - PROCESS | 1659 | 16:00:29 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:71665.06 Median:19847.55 stddev:159052.75 (801.4%) stddev-sans-first:159409.20 16:00:29 INFO - PROCESS | 1659 | Values: 505.5 515.2 517.4 543.5 547.2 30185.1 31014.5 31872.0 31875.2 31930.0 42251.8 43511.6 43627.9 46252.5 46303.3 9514.6 9671.9 9678.3 9686.0 9692.1 8459.3 8683.1 9475.1 9594.2 9610.6 11477.9 11730.0 11749.7 12046.2 12052.4 11597.2 11717.6 11725.4 11727.9 11733.6 8447.1 11295.3 11488.8 11656.7 11806.6 38931.6 39266.6 39270.6 39340.0 39347.2 3126.3 3177.9 3235.8 3408.4 3803.4 27802.6 28292.9 28486.4 28791.9 29098.9 2677.8 2702.5 2719.8 2749.1 2831.2 501719.0 502851.0 503217.0 503979.0 504638.0 57606.4 58490.7 58493.4 58501.0 58519.7 62048.7 62116.0 62116.7 62171.8 62333.7 16309.3 16803.5 16827.4 16831.0 16832.8 13065.7 13098.0 13104.4 13138.3 13149.7 13651.3 14007.0 15279.5 15514.8 15536.8 5603.5 6238.2 6692.2 6736.6 6753.0 695.1 945.3 1024.6 1049.5 1076.1 27746.9 28382.1 28973.0 29442.9 29662.6 1103.8 1105.2 1108.1 1109.0 1146.4 14913.7 14923.5 15009.5 15319.8 15364.2 673547.0 678337.0 678716.0 678737.0 680056.0 59549.7 59604.0 59638.0 59679.7 59689.8 1317.2 1321.1 1335.7 1430.6 1479.0 28608.3 29418.9 29752.0 29913.9 30814.8 57861.4 58202.8 58638.7 58672.4 58831.0 582911.0 588594.0 589796.0 589816.0 591297.0 25186.1 25341.2 26031.5 26090.5 26903.0 45681.5 45913.0 46200.5 46751.3 47334.0 36304.9 36309.1 37545.8 38037.8 38067.4 36286.7 36831.1 37221.8 37662.8 38199.6 36588.5 36899.6 36908.4 37612.6 37756.4 31901.2 33169.0 33363.7 33382.0 33396.3 20595.8 22852.7 23885.0 24192.9 24832.9 16:00:29 INFO - PROCESS | 1659 | 16:00:29 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:34359.88 Median:394.30 stddev:102106.92 (25895.6%) stddev-sans-first:102361.50 16:00:29 INFO - PROCESS | 1659 | Values: 390.6 390.9 392.3 396.3 417.4 20027.5 20282.3 21253.6 21335.7 21353.1 20039.9 20178.2 21008.6 21088.3 21092.7 5041.5 5398.5 5410.4 5423.2 5425.6 5249.3 5264.1 5358.1 5363.4 5367.8 5220.8 5385.8 5409.7 5411.6 5417.3 4485.6 5311.3 5325.9 5355.8 5707.3 5874.7 5877.7 5894.3 5901.7 5912.3 13505.5 13508.0 13559.6 13860.1 13885.1 1958.3 1986.2 2224.5 2370.4 2674.7 9615.6 9712.4 9776.4 10022.3 10191.7 1559.9 1575.4 1716.6 1791.7 1805.2 116949.2 118530.4 121511.0 121994.0 124888.4 9443.0 11404.1 11548.5 11588.8 11686.9 14614.0 14754.5 14758.0 14760.0 14764.3 8603.5 8794.3 8815.8 8907.8 8914.4 7020.3 8825.7 8826.2 8919.2 8944.5 4502.8 4605.7 4610.8 4644.6 4683.1 1737.0 1894.2 1899.1 1957.4 1958.1 831.1 913.7 941.1 962.4 1045.8 19447.3 20262.8 20392.6 20449.6 20600.5 908.5 909.2 944.6 954.8 1019.0 8337.7 10697.5 10716.3 10764.2 10781.0 600356.0 606414.0 616807.0 618392.0 624555.0 89561.0 103510.0 104215.0 132189.0 136674.0 1008.2 1008.8 1031.0 1032.1 1034.4 14625.9 17190.5 17646.9 17666.3 18112.4 39865.0 52588.9 52790.5 55682.9 56754.1 75312.2 75425.6 76003.0 76266.0 78381.8 6872.2 7264.6 7324.9 7365.1 8258.1 9006.0 12270.4 12355.9 12690.3 12757.7 5462.0 6916.5 10709.3 10851.9 11082.8 9591.4 10270.1 10886.5 11060.0 11230.0 4578.5 6515.4 8870.8 11165.3 11398.6 7223.5 7309.9 7381.9 7386.3 7624.9 8226.3 10542.6 10909.2 11204.0 11235.1 16:00:29 INFO - PROCESS | 1659 | 16:00:29 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5586.24 Median:4877.90 stddev:3133.94 (64.2%) stddev-sans-first:3127.04 16:00:29 INFO - PROCESS | 1659 | Values: 1412.4 1527.5 1558.5 1562.7 1981.6 7745.0 8471.7 8671.0 8671.0 8808.3 8408.6 8559.2 8653.5 8662.0 8667.5 2059.6 2128.0 2132.6 2293.3 2335.0 2047.2 2073.1 2284.5 2342.5 2345.1 2326.0 2334.0 2338.6 2338.6 2338.7 2041.6 2285.3 2301.3 2324.8 2329.0 2025.5 2280.0 2345.1 2345.9 2361.5 8574.3 8635.6 8645.2 8653.3 8676.0 5139.8 5294.1 5306.6 5311.5 5324.4 3476.6 3496.6 3501.8 3584.8 3588.4 971.7 1009.9 1014.2 1014.5 1022.4 9701.8 11537.9 11565.1 11571.2 11595.8 3374.8 4623.5 4725.6 4786.4 4796.1 9701.3 9719.3 9723.2 9725.9 9739.1 5215.5 5278.0 5278.2 5286.2 5286.9 4701.0 4801.8 4869.0 4886.8 4893.2 3896.3 3919.2 3930.3 3969.9 4035.7 1259.8 1281.6 1285.8 1296.9 1298.4 635.6 694.8 825.7 907.9 949.7 5281.9 5326.7 5331.2 5414.2 5488.2 985.9 1003.0 1009.9 1022.4 1022.6 3837.9 3915.0 3972.5 3992.8 4005.6 10035.5 10613.8 10626.2 10628.1 10679.0 10054.3 10446.2 10576.3 10590.9 10634.3 7875.5 8181.0 8203.2 8208.3 8208.8 5712.4 5825.2 5852.7 5920.5 5948.0 9807.5 9877.0 9939.0 9947.7 9952.4 7723.7 9545.6 9557.1 10091.2 10101.7 7367.0 7489.6 7569.0 7576.0 7582.8 8010.4 8052.3 8101.2 8101.6 8162.5 7369.6 7411.7 7420.1 7544.3 7602.3 7063.3 7284.0 7284.6 7315.4 7444.7 6881.4 6911.5 6917.8 6991.2 6997.5 3974.4 4512.9 4845.9 4855.5 4896.8 4249.6 4764.7 4808.7 4811.5 4847.7 16:00:29 INFO - PROCESS | 1659 | 16:00:29 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4450.64 Median:5001.99 stddev:1390.52 (27.8%) stddev-sans-first:1359.06 16:00:29 INFO - PROCESS | 1659 | Values: 298.9 306.5 316.6 318.6 320.6 4638.0 4672.8 4909.9 5271.3 5291.2 5017.4 5024.9 5162.4 5207.4 5252.6 3417.8 3649.9 4080.1 4091.3 4104.6 4091.4 4107.4 4117.9 4118.6 4132.0 2917.5 3182.8 3676.8 4082.7 4105.7 3145.8 3707.9 4040.9 4068.9 4082.4 2977.5 3544.8 3554.5 3582.7 3595.8 3983.2 4093.5 4640.8 5115.9 5123.5 3842.9 4489.8 4544.0 4562.9 4571.3 4649.3 4768.8 5539.3 5550.9 5567.5 3384.7 3713.4 3803.5 3815.3 3834.3 5127.9 5173.9 5817.5 5855.4 5889.3 3036.1 3706.7 3786.7 3859.6 3862.2 3606.8 3807.2 3920.7 3928.9 3943.5 3984.6 4292.5 4543.3 4548.2 4556.2 5098.8 5401.7 5493.5 5512.5 5533.7 1208.4 1212.2 1251.2 1331.2 1344.0 733.9 754.6 778.6 780.6 781.9 4478.6 4699.3 5126.4 5246.4 5254.8 4806.3 5338.9 5672.6 5718.3 5763.8 4986.6 5219.6 5231.9 5233.5 5249.0 2749.5 2833.9 3006.0 3035.5 3040.7 5271.9 5580.6 5876.9 5931.2 5963.3 5204.2 5396.8 5519.9 5876.0 5881.5 5325.4 5922.5 5922.7 5929.0 5951.6 5324.3 5586.8 5642.2 5741.0 5747.9 5328.9 5635.8 5647.0 5670.9 5683.7 4785.6 4847.5 5582.7 5715.7 5911.8 3082.7 3952.9 3985.6 3992.1 4020.7 5418.3 5763.1 5794.6 5801.4 5868.2 5209.3 5343.4 5428.9 5555.1 5631.9 4548.0 4934.0 5392.3 5579.6 5656.0 4569.7 4924.3 5246.3 5528.5 5545.4 4805.0 4807.7 4894.9 5194.1 5651.0 5492.4 5516.8 5542.1 5546.8 5549.7 16:00:29 INFO - PROCESS | 1659 | 16:00:29 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:17754.05 Median:3563.36 stddev:24983.41 (701.1%) stddev-sans-first:25020.44 16:00:29 INFO - PROCESS | 1659 | Values: 637.7 668.7 691.5 711.1 753.6 14816.6 15036.5 15190.6 15381.0 15388.7 14463.0 14533.3 14593.1 14596.3 14653.0 728.7 753.2 893.8 900.5 911.6 745.2 746.0 748.3 750.0 865.9 957.8 965.7 966.1 966.6 969.0 951.4 958.7 959.5 960.8 965.9 556.0 678.9 699.5 710.9 711.6 14688.0 14746.6 14769.2 14792.8 14815.8 11936.5 12056.4 12198.6 12271.0 12352.4 10707.9 10852.8 10992.2 11063.2 11414.7 4044.0 4091.8 4092.8 4134.2 4250.2 74105.0 76262.5 76468.0 76623.0 78827.0 9214.7 9280.5 9299.3 9621.0 9736.3 21183.0 21200.0 21210.5 21214.3 21235.7 7211.5 7233.0 7263.4 7287.5 7411.2 3935.9 4047.9 4091.3 4092.5 4102.3 3347.2 3449.8 3456.7 3463.1 3484.2 628.0 633.8 645.4 648.1 659.5 497.5 521.6 524.3 543.4 564.0 7173.6 7229.8 7289.7 7360.3 7630.8 470.0 492.7 538.2 553.1 575.9 3492.3 3518.8 3532.6 3610.8 3688.3 74417.0 84425.3 85285.6 90122.5 90178.5 78529.6 80841.0 81206.6 81522.0 82898.8 66999.7 67640.3 73460.6 74098.8 74978.3 6478.6 6612.9 6725.8 6726.2 7119.7 44544.0 46477.8 47372.5 48979.0 49124.5 62532.0 65556.7 65773.7 65934.7 67031.3 9713.5 9840.9 9895.8 9913.1 10243.9 9150.0 10479.7 10535.1 10612.4 10696.5 9778.1 10085.1 10318.3 11026.2 11133.3 10588.2 10816.4 10833.5 10885.5 11525.1 10184.7 10936.5 11175.7 11230.2 11996.8 8442.9 9014.0 9174.7 9224.1 9356.0 9322.6 9470.8 9493.3 9555.0 9699.3 16:00:29 INFO - PROCESS | 1659 | -------- Summary: end -------- 16:00:29 INFO - PROCESS | 1659 | 16:00:29 INFO - TEST-INFO | 1659: exit 0 16:00:29 INFO - TEST-OK | dromaeo_css | took 1115618ms 16:00:29 INFO - TEST-START | kraken 16:00:29 INFO - operating with platform_type : mac_ 16:00:29 INFO - Initialising browser for kraken test... 16:00:29 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmprGSUE9/profile http://localhost:49271/getInfo.html) 16:00:31 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 16:00:31 INFO - PROCESS | 1669 | colorDepth:24 16:00:31 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 16:00:31 INFO - PROCESS | 1669 | __metrics 16:00:31 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" 16:00:31 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:00:32 INFO - TEST-INFO | 1669: exit 0 16:00:32 INFO - Browser initialized. 16:00:32 INFO - Running cycle 1/1 for kraken test... 16:00:32 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmprGSUE9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:00:44 INFO - PROCESS | 1670 | RSS: Main: 241131520 16:00:44 INFO - PROCESS | 1670 | 16:00:47 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 16:00:48 INFO - PROCESS | 1670 | RSS: Main: 288522240 16:00:48 INFO - PROCESS | 1670 | 16:00:50 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 16:00:51 INFO - PROCESS | 1670 | RSS: Main: 297304064 16:00:51 INFO - PROCESS | 1670 | 16:00:54 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 16:00:55 INFO - PROCESS | 1670 | RSS: Main: 388333568 16:00:55 INFO - PROCESS | 1670 | 16:00:57 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 16:00:58 INFO - PROCESS | 1670 | RSS: Main: 287821824 16:00:58 INFO - PROCESS | 1670 | 16:01:00 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:01:00 INFO - PROCESS | 1670 | RSS: Main: 290762752 16:01:00 INFO - PROCESS | 1670 | 16:01:03 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 16:01:04 INFO - PROCESS | 1670 | RSS: Main: 328478720 16:01:04 INFO - PROCESS | 1670 | 16:01:06 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 16:01:07 INFO - PROCESS | 1670 | RSS: Main: 329764864 16:01:07 INFO - PROCESS | 1670 | 16:01:10 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 16:01:11 INFO - PROCESS | 1670 | RSS: Main: 329621504 16:01:11 INFO - PROCESS | 1670 | 16:01:12 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:01:13 INFO - PROCESS | 1670 | RSS: Main: 315756544 16:01:13 INFO - PROCESS | 1670 | 16:01:15 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:01:15 INFO - PROCESS | 1670 | RSS: Main: 322125824 16:01:15 INFO - PROCESS | 1670 | 16:01:17 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:01:18 INFO - PROCESS | 1670 | RSS: Main: 323883008 16:01:18 INFO - PROCESS | 1670 | 16:01:21 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:01:22 INFO - PROCESS | 1670 | RSS: Main: 328409088 16:01:22 INFO - PROCESS | 1670 | 16:01:25 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:01:25 INFO - PROCESS | 1670 | RSS: Main: 327905280 16:01:25 INFO - PROCESS | 1670 | 16:01:27 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 16:01:27 INFO - PROCESS | 1670 | RSS: Main: 374112256 16:01:27 INFO - PROCESS | 1670 | 16:01:27 INFO - PROCESS | 1670 | __start_tp_report 16:01:27 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 16:01:27 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 16:01:27 INFO - PROCESS | 1670 | |i|pagename|runs| 16:01:27 INFO - PROCESS | 1670 | |0;ai-astar;112;107;113;112;113;113;113;113;112;115 16:01:27 INFO - PROCESS | 1670 | |1;audio-beat-detection;168;150;142;146;119;142;136;118;176;140 16:01:27 INFO - PROCESS | 1670 | |2;audio-dft;182;183;183;182;181;180;182;184;181;182 16:01:27 INFO - PROCESS | 1670 | |3;audio-fft;78;57;61;85;58;57;62;68;102;79 16:01:27 INFO - PROCESS | 1670 | |4;audio-oscillator;84;68;99;70;81;68;92;70;69;97 16:01:27 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;87;86;91;85;88;84;88;83;87;83 16:01:27 INFO - PROCESS | 1670 | |6;imaging-darkroom;93;95;95;94;94;94;95;94;94;94 16:01:27 INFO - PROCESS | 1670 | |7;imaging-desaturate;83;85;86;84;86;84;84;88;136;143 16:01:27 INFO - PROCESS | 1670 | |8;json-parse-financial;59;59;60;57;59;60;59;75;58;56 16:01:27 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;65;65;78;77;75;74;70;73;70;71 16:01:27 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;86;83;85;86;86;84;85;85;86;88 16:01:27 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;146;138;145;145;140;143;139;138;175;138 16:01:27 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;198;163;166;165;164;163;164;164;163;162 16:01:27 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;68;66;66;66;66;66;65;65;64;65 16:01:27 INFO - PROCESS | 1670 | __end_tp_report 16:01:27 INFO - PROCESS | 1670 | __start_cc_report 16:01:27 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,2093 16:01:27 INFO - PROCESS | 1670 | __end_cc_report 16:01:27 INFO - PROCESS | 1670 | __startTimestamp1464217287774__endTimestamp 16:01:27 INFO - PROCESS | 1670 | 16:01:27 INFO - PROCESS | 1670 | ------- Summary: start ------- 16:01:27 INFO - PROCESS | 1670 | Number of tests: 14 16:01:27 INFO - PROCESS | 1670 | 16:01:27 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:112.30 Median:113.00 stddev:2.06 (1.8%) stddev-sans-first:2.18 16:01:27 INFO - PROCESS | 1670 | Values: 112.0 107.0 113.0 112.0 113.0 113.0 113.0 113.0 112.0 115.0 16:01:27 INFO - PROCESS | 1670 | 16:01:27 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:143.70 Median:144.00 stddev:18.34 (12.7%) stddev-sans-first:17.22 16:01:27 INFO - PROCESS | 1670 | Values: 168.0 150.0 142.0 146.0 119.0 142.0 136.0 118.0 176.0 140.0 16:01:27 INFO - PROCESS | 1670 | 16:01:27 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:182.00 Median:182.00 stddev:1.15 (0.6%) stddev-sans-first:1.22 16:01:27 INFO - PROCESS | 1670 | Values: 182.0 183.0 183.0 182.0 181.0 180.0 182.0 184.0 181.0 182.0 16:01:27 INFO - PROCESS | 1670 | 16:01:27 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:70.70 Median:65.00 stddev:14.98 (23.0%) stddev-sans-first:15.66 16:01:27 INFO - PROCESS | 1670 | Values: 78.0 57.0 61.0 85.0 58.0 57.0 62.0 68.0 102.0 79.0 16:01:27 INFO - PROCESS | 1670 | 16:01:27 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:79.80 Median:82.50 stddev:12.56 (15.2%) stddev-sans-first:13.23 16:01:27 INFO - PROCESS | 1670 | Values: 84.0 68.0 99.0 70.0 81.0 68.0 92.0 70.0 69.0 97.0 16:01:27 INFO - PROCESS | 1670 | 16:01:27 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:86.20 Median:87.00 stddev:2.53 (2.9%) stddev-sans-first:2.67 16:01:27 INFO - PROCESS | 1670 | Values: 87.0 86.0 91.0 85.0 88.0 84.0 88.0 83.0 87.0 83.0 16:01:27 INFO - PROCESS | 1670 | 16:01:27 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 16:01:27 INFO - PROCESS | 1670 | Values: 93.0 95.0 95.0 94.0 94.0 94.0 95.0 94.0 94.0 94.0 16:01:27 INFO - PROCESS | 1670 | 16:01:27 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:84.50 stddev:23.08 (27.3%) stddev-sans-first:24.01 16:01:27 INFO - PROCESS | 1670 | Values: 83.0 85.0 86.0 84.0 86.0 84.0 84.0 88.0 136.0 143.0 16:01:27 INFO - PROCESS | 1670 | 16:01:27 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:60.20 Median:59.00 stddev:5.35 (9.1%) stddev-sans-first:5.66 16:01:27 INFO - PROCESS | 1670 | Values: 59.0 59.0 60.0 57.0 59.0 60.0 59.0 75.0 58.0 56.0 16:01:27 INFO - PROCESS | 1670 | 16:01:27 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:73.50 stddev:4.49 (6.1%) stddev-sans-first:4.03 16:01:27 INFO - PROCESS | 1670 | Values: 65.0 65.0 78.0 77.0 75.0 74.0 70.0 73.0 70.0 71.0 16:01:27 INFO - PROCESS | 1670 | 16:01:27 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:86.00 stddev:1.35 (1.6%) stddev-sans-first:1.41 16:01:27 INFO - PROCESS | 1670 | Values: 86.0 83.0 85.0 86.0 86.0 84.0 85.0 85.0 86.0 88.0 16:01:27 INFO - PROCESS | 1670 | 16:01:27 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:144.00 stddev:11.12 (7.7%) stddev-sans-first:11.78 16:01:27 INFO - PROCESS | 1670 | Values: 146.0 138.0 145.0 145.0 140.0 143.0 139.0 138.0 175.0 138.0 16:01:27 INFO - PROCESS | 1670 | 16:01:27 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.20 Median:164.00 stddev:10.88 (6.6%) stddev-sans-first:1.20 16:01:27 INFO - PROCESS | 1670 | Values: 198.0 163.0 166.0 165.0 164.0 163.0 164.0 164.0 163.0 162.0 16:01:27 INFO - PROCESS | 1670 | 16:01:27 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:1.06 (1.6%) stddev-sans-first:0.73 16:01:27 INFO - PROCESS | 1670 | Values: 68.0 66.0 66.0 66.0 66.0 66.0 65.0 65.0 64.0 65.0 16:01:27 INFO - PROCESS | 1670 | -------- Summary: end -------- 16:01:27 INFO - PROCESS | 1670 | 16:01:28 INFO - TEST-INFO | 1670: exit 0 16:01:28 INFO - TEST-OK | kraken | took 58831ms 16:01:28 INFO - SUITE-END | took 1174s 16:01:28 INFO - Completed test suite (00:19:34) 16:01:28 INFO - javascript benchmark 16:01:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3441.1741182116293, 3459.927422499024, 3483.7038851697603, 3486.2853115111407, 3529.48822420749, 5618.4662457066615, 5644.0, 5671.6597004179675, 5700.401457846923, 5736.1682556029355, 5595.8601034974145, 5682.857928551788, 5693.572982026344, 5702.4012478689765, 5723.713814309289, 5102.234664800272, 5141.614378921578, 5208.609354298424, 5224.45143259958, 5318.335208098988, 5323.574114070865, 5327.200919862013, 5354.812581559646, 5371.865703357408, 5412.702301373423, 4290.549492303295, 5136.43499215086, 5189.558887494568, 5241.344831896013, 5247.606429517801, 5244.842654720364, 5251.212318152269, 5254.605904557174, 5264.341957255343, 5269.367675878919, 5154.845354639329, 5175.223716442526, 5217.191335343028, 5286.20706893969, 5298.099323115041, 5564.221009058694, 5604.243427137325, 5622.072358060915, 5680.147977803321, 5692.5161361284345, 5155.278261043457, 6226.906596401012, 6247.468965137969, 6252.6561039142825, 6253.437190652863, 6122.755089796403, 6131.325554189036, 6137.622145771035, 6218.782342617987, 6224.221972253468, 5875.1187321901625, 5889.764409423661, 5891.617044892068, 5901.144334071536, 5919.792807251769, 6258.499320054385, 6261.029540421151, 6269.247690277196, 6273.623582584968, 6278.560500764903, 6031.366706495843, 6110.205673262448, 6139.201903752483, 6146.446819786241, 6193.0, 6199.907001394983, 6226.533010024266, 6231.781887633911, 6235.781747638811, 6236.501079913596, 6073.180120683651, 6191.84520386994, 6217.222847144107, 6218.409251121137, 6231.096491008779, 6115.115075256843, 6116.853194528077, 6180.134781130645, 6184.010558310649, 6267.341929097471, 4836.830710925171, 4838.517577465868, 4877.0, 4904.558589726942, 4928.605711543068, 4311.137772445523, 4451.443569553806, 4461.152381047591, 4481.462224533077, 4512.2329204035395, 5540.473655002768, 5611.2144299798065, 5613.354464236665, 5613.775448982076, 5620.241267428844, 6128.625347140816, 6130.153553065287, 6174.228221472316, 6202.534809889276, 6293.244810622755, 5750.079987202028, 5759.049756790083, 5761.0, 5772.5959182856795, 5788.565857560699, 5344.305240318734, 5357.276767636397, 5377.489138531833, 5384.865378365572, 5386.245925570423, 5226.268322434862, 6219.40915613016, 6226.066090086523, 6277.466415354746, 6292.339304373067, 6284.340144284967, 6302.810915672537, 6309.7160627770945, 6309.810705678837, 6328.936710633019, 6254.562180647311, 6328.303886572381, 6328.841778955563, 6331.556791024584, 6338.778142764981, 5580.693061881636, 6000.669963152069, 6029.427204415573, 6122.367660829284, 6127.006280530498, 6275.0, 6282.026285925689, 6282.277538083179, 6291.087792270004, 6296.779612713533, 5901.203337549376, 6110.824435359022, 6140.815775526741, 6141.056449558148, 6148.1085242640465, 6045.274567051982, 6084.817455476343, 6110.877782444238, 6141.6007959482495, 6157.922363586444, 5243.790248390174, 5305.283786688902, 5560.499555040065, 5934.792282270272, 5994.730237139252, 5983.640981541122, 5989.221401217814, 6129.601575897553, 6140.232470941132, 6151.446369826737, 5837.445442682938, 5902.881942361043, 5916.881662366814, 5921.99326114553, 5954.910676339771], "value": 5675.961533049611, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.46925727985627, 515.1757188498403, 517.4411635433732, 543.5325619966831, 547.1956224350205, 30185.094447166593, 31014.534781978287, 31872.0, 31875.203119922015, 31930.0, 42251.78874105633, 43511.564884351144, 43627.90930226738, 46252.53747462524, 46303.305450418186, 9514.571844267024, 9671.854922176173, 9678.274129440286, 9685.951570242174, 9692.079252471003, 8459.323254139654, 8683.13168683133, 9475.099865512766, 9594.184494317957, 9610.567524461416, 11477.909598588121, 11730.002949749281, 11749.706257343549, 12046.15676902617, 12052.397380131002, 11597.188196826226, 11717.589884354007, 11725.355105469238, 11727.941360293229, 11733.647990560297, 8447.070822209553, 11295.265807722471, 11488.827667584992, 11656.708582285426, 11806.645800625995, 38931.61068389336, 39266.60733392658, 39270.60729392698, 39340.0, 39347.21305573901, 3126.3278395145153, 3177.9205519861957, 3235.8382080895863, 3408.3694516514274, 3803.4294855771855, 27802.582961255393, 28292.858535707397, 28486.430271394456, 28791.85604071987, 29098.92641844842, 2677.8259413138085, 2702.5405681034076, 2719.7960152988603, 2749.0653177919403, 2831.2214141111153, 501719.0, 502851.0, 503216.99999999633, 503979.0, 504638.0, 57606.42393576095, 58490.70754646199, 58493.4150658488, 58501.0, 58519.70740146272, 62048.68975655093, 62116.0, 62116.689416552625, 62171.756564869356, 62333.68833155805, 16309.347626094823, 16803.49589512292, 16827.41104061352, 16830.990140591603, 16832.831671683125, 13065.673358166121, 13098.0, 13104.410301536454, 13138.343082845819, 13149.737005260033, 13651.317434128254, 14007.0, 15279.541613751606, 15514.767278490832, 15536.83473739474, 5603.5237004854225, 6238.1890354254565, 6692.163479565054, 6736.562123461959, 6752.966235168891, 695.085961960021, 945.2579724915907, 1024.6379761414262, 1049.5277125293649, 1076.1229597877705, 27746.890124395202, 28382.124197343805, 28972.985945491808, 29442.852785736362, 29662.55506167409, 1103.8233882578909, 1105.165599972016, 1108.141190577309, 1109.0, 1146.4153281826161, 14913.70172596542, 14923.477678281208, 15009.549713508612, 15319.846801532065, 15364.231788410758, 673547.0, 678337.0, 678716.0, 678737.0, 680056.0, 59549.70225148847, 59604.0, 59638.0, 59679.70160149172, 59689.80620387655, 1317.2096741954942, 1321.1280554833957, 1335.7194989052407, 1430.5565274764856, 1478.9852101479062, 28608.284792879513, 29418.852905735333, 29752.0, 29913.85043074771, 30814.845925770227, 57861.4213857856, 58202.83594328005, 58638.70680646569, 58672.4132758667, 58831.0, 582911.0, 588594.0, 589796.0, 589816.0, 591297.0, 25186.11848585291, 25341.239762807145, 26031.479370412864, 26090.478190436465, 26903.0, 45681.54318456906, 45913.0, 46200.537994620965, 46751.29873051975, 47334.0, 36304.91085267446, 36309.09827173404, 37545.81227093847, 38037.80981095077, 38067.42898856464, 36286.729964451115, 36831.132731062855, 37221.813890930374, 37662.841345292654, 38199.61800382072, 36588.45117323189, 36899.63100368962, 36908.44637330389, 37612.62387376201, 37756.43365349467, 31901.20246993844, 33169.0, 33363.66636333606, 33382.0, 33396.332073358884, 20595.794042059388, 22852.657210142195, 23885.044598215878, 24192.87903560471, 24832.875835620707], "value": 19803.05319791544, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.56061930328383, 390.8631978807418, 392.2860394082767, 396.31833246815484, 417.3989455184534, 20027.499312517175, 20282.290119845773, 21253.57492850142, 21335.67996480054, 21353.145874165035, 20039.897805620683, 20178.192872285126, 21008.579828403424, 21088.261910833124, 21092.683609745865, 5041.470645582216, 5398.541124004464, 5410.404855465896, 5423.159410291411, 5425.593080518957, 5249.343832020997, 5264.078786212397, 5358.115910874701, 5363.436839131893, 5367.75845086972, 5220.817271395502, 5385.78456861725, 5409.729513524328, 5411.621186516945, 5417.349918009844, 4485.641148708128, 5311.282976798141, 5325.893482130375, 5355.812546560871, 5707.286589176353, 5874.73563689635, 5877.735501902382, 5894.263217097863, 5901.704914754245, 5912.320083190402, 13505.514393416715, 13508.0, 13559.59321220365, 13860.099093558987, 13885.097468664504, 1958.3439547751377, 1986.2055177928798, 2224.477247846755, 2370.4310965368186, 2674.665666791651, 9615.615375384976, 9712.368696034735, 9776.364536305118, 10022.3485473444, 10191.745206369826, 1559.8674112700435, 1575.4328441760956, 1716.648087142133, 1791.7491551182845, 1805.2147315917487, 116949.24576131365, 118530.40734796357, 121510.98451201171, 121994.0, 124888.37555812254, 9442.952785236168, 11404.144689148347, 11548.538058477567, 11588.768224635629, 11686.88313116875, 14614.0, 14754.483593074405, 14758.0, 14760.040597361138, 14764.26178691061, 8603.483790972561, 8794.296456283482, 8815.779605509913, 8907.777305567282, 8914.420562663407, 7020.333068358498, 8825.69110081144, 8826.205641492297, 8919.241864441465, 8944.50805205707, 4502.774861256924, 4605.700629459075, 4610.76946152691, 4644.58198762113, 4683.110209060268, 1736.9913150434168, 1894.1665667106429, 1899.069455966594, 1957.4225603447017, 1958.099274333794, 831.08580561382, 913.6665117232162, 941.1435593609782, 962.3888830592624, 1045.8431235314763, 19447.3193438229, 20262.797372026387, 20392.59214815725, 20449.591008179752, 20600.48498787542, 908.5457271364318, 909.1544863277084, 944.617429940875, 954.8281309364381, 1019.0, 8337.708180213785, 10697.518611662494, 10716.303440276357, 10764.246502744732, 10781.0, 600356.0, 606414.0, 616807.0, 618392.0, 624555.0, 89561.0, 103509.96490035154, 104214.95785042053, 132189.0, 136674.0, 1008.2085562833141, 1008.7831116309882, 1030.99484502577, 1032.1175395037135, 1034.363866222268, 14625.85374146245, 17190.484285471455, 17646.91176544109, 17666.293348265925, 18112.366067187584, 39865.0, 52588.9482210346, 52790.47209528009, 55682.88634227374, 56754.14868776889, 75312.24687753271, 75425.6228718853, 76003.0, 76266.0, 78381.82427263481, 6872.244053154249, 7264.636768161465, 7324.926750732425, 7365.079365079365, 8258.132896045829, 9005.961651010432, 12270.386480675752, 12355.899497085336, 12690.302033387883, 12757.744845103232, 5461.972690136524, 6916.515843890879, 10709.250352475563, 10851.945740271249, 11082.783443311531, 9591.376560523544, 10270.127039201452, 10886.455677215949, 11060.0, 11230.04544613717, 4578.473475550221, 6515.446187073962, 8870.822583548423, 11165.330080195214, 11398.575178102737, 7223.458240631869, 7309.890351644836, 7381.852362952818, 7386.335229829127, 7624.923750762421, 8226.341892648454, 10542.57829686804, 10909.181811364339, 11203.99164075237, 11235.101191904465], "value": 9034.005902899307, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1412.4420853762758, 1527.503561342564, 1558.4623304959791, 1562.7265228585002, 1981.6036792641466, 7745.0, 8471.703490377839, 8671.0, 8671.046184919654, 8808.339374546918, 8408.579571021455, 8559.186877246651, 8653.524056176908, 8661.956690216555, 8667.523286219272, 2059.557195203031, 2128.0423809285808, 2132.648113061343, 2293.334932869466, 2334.9533009339802, 2047.170895787201, 2073.139647046474, 2284.520250747337, 2342.484653376263, 2345.1205797825814, 2325.9767402326015, 2334.0, 2338.555674421863, 2338.5790557699606, 2338.684277622516, 2041.6018876319124, 2285.3029825903095, 2301.2520930697506, 2324.8023917966993, 2329.033451117784, 2025.473376921996, 2279.951222437681, 2345.0502546468706, 2345.906163753448, 2361.5276944611146, 8574.314054875595, 8635.568221588957, 8645.221930026328, 8653.307735381155, 8676.045634980148, 5139.8458046258675, 5294.126469132589, 5306.575473962073, 5311.521963023308, 5324.41431442541, 3476.582810062783, 3496.6328535503662, 3501.77238479498, 3584.8386822593047, 3588.3540962626716, 971.7182017214999, 1009.9495025248708, 1014.178515402519, 1014.5181039006486, 1022.3610243597751, 9701.805963880683, 11537.94231028848, 11565.132615053904, 11571.247868888495, 11595.826062609067, 3374.7806392584403, 4623.514530974267, 4725.598324142417, 4786.353842231324, 4796.088743138793, 9701.320907536547, 9719.31964762474, 9723.222142228604, 9725.854112188323, 9739.123478886933, 5215.47845215483, 5278.0, 5278.155495120763, 5286.20706893969, 5286.9471305287225, 4701.0, 4801.831935882227, 4869.001854619865, 4886.804527818918, 4893.241547560134, 3896.3376226041073, 3919.1573811630637, 3930.292547341506, 3969.861054863066, 4035.6771458283265, 1259.80473026681, 1281.5834853672593, 1285.8135570342436, 1296.8962483001337, 1298.3832679477362, 635.5678138865524, 694.8297667071599, 825.7481468152271, 907.9273658107336, 949.715085474355, 5281.9207711884355, 5326.733663316819, 5331.173668081454, 5414.187871819258, 5488.17677348401, 985.9014098590227, 1002.9899701003043, 1009.9192064634811, 1022.4070039377292, 1022.5909636145601, 3837.865674701372, 3915.0, 3972.4835771350117, 3992.8203230854683, 4005.639492445694, 10035.498225088862, 10613.84079238812, 10626.203034772423, 10628.09661178793, 10678.985496377833, 10054.34646747959, 10446.164306855586, 10576.31253968505, 10590.947045264878, 10634.255602107778, 7875.488093273919, 8181.0, 8203.179682031749, 8208.343332533503, 8208.83582328362, 5712.400197979318, 5825.184474173704, 5852.707364631837, 5920.496757775637, 5948.0, 9807.509624518889, 9876.962918893412, 9938.950305248427, 9947.651832185838, 9952.353097048668, 7723.652435640522, 9545.61817527319, 9557.092076252746, 10091.243156763125, 10101.69694909154, 7366.96316518414, 7489.588072656056, 7568.962155189189, 7576.0, 7582.848343032998, 8010.3992200584025, 8052.315553178046, 8101.230383113595, 8101.635426405944, 8162.510249385056, 7369.631518424165, 7411.740589079356, 7420.109586849612, 7544.283293087148, 7602.315791578784, 7063.258357872555, 7284.0, 7284.562926224232, 7315.414766818748, 7444.739434119996, 6881.380675739208, 6911.481638877004, 6917.827054323682, 6991.161060672752, 6997.510174287954, 3974.3641017438226, 4512.864614061584, 4845.878853028703, 4855.514448555116, 4896.779644915916, 4249.617534421917, 4764.690295130806, 4808.663393562557, 4811.542903424169, 4847.691123396734], "value": 4483.940988375051, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.92228020714623, 306.4606292924453, 316.58685415532733, 318.6351627386644, 320.56563356651736, 4638.026014536943, 4672.766361681911, 4909.895273563447, 5271.288376069234, 5291.2327712481665, 5017.42299635542, 5024.874378140548, 5162.380514338284, 5207.375114986207, 5252.553532949704, 3417.820851806135, 3649.9270014599692, 4080.1227736036744, 4091.338252264222, 4104.630583247515, 4091.3610721084883, 4107.404426358176, 4117.8764637060785, 4118.629323360912, 4131.979340103296, 2917.53319468885, 3182.76129290303, 3676.7977761223124, 4082.6733861291173, 4105.712600117993, 3145.779795414322, 3707.925841483169, 4040.9191816163807, 4068.9186216275803, 4082.4488694026372, 2977.5384815353655, 3544.8404821782824, 3554.4940816724147, 3582.707067476817, 3595.7662751921043, 3983.223271462066, 4093.5087789465456, 4640.814367425332, 5115.923261151086, 5123.462036486189, 3842.884713458601, 4489.842855500042, 4544.0, 4562.946673996715, 4571.314302854598, 4649.349091127245, 4768.7854046567645, 5539.307586551681, 5550.86122846928, 5567.526760225385, 3384.7461440392067, 3713.3687273163628, 3803.5435747710176, 3815.332316844569, 3834.2523207974455, 5127.87180320495, 5173.870653233662, 5817.534597232254, 5855.443732845373, 5889.322727886348, 3036.073997430805, 3706.6849317808287, 3786.659200671953, 3859.6333348331864, 3862.1503269280715, 3606.765560238576, 3807.2195199983935, 3920.6863450923856, 3928.8821335359985, 3943.4873466449176, 3984.62530427008, 4292.48490181174, 4543.318502224693, 4548.226801443762, 4556.20266453376, 5098.796048158107, 5401.702906340189, 5493.533049690821, 5512.50387465122, 5533.723313834373, 1208.3656080557778, 1212.1514939542355, 1251.192980527573, 1331.227887825059, 1343.9596812095654, 733.8771679330632, 754.5548126605329, 778.5873487051872, 780.5511830697371, 781.9452638315263, 4478.641708663299, 4699.318598803155, 5126.410462796752, 5246.396664383568, 5254.789808407697, 4806.255030470283, 5338.878835444601, 5672.602917795714, 5718.285214348207, 5763.798267060716, 4986.551210391082, 5219.555093638181, 5231.881088159302, 5233.502817232356, 5249.028929648028, 2749.4913441013477, 2833.9008134715186, 3006.0, 3035.5446682997285, 3040.6503252125844, 5271.947280527223, 5580.637258578179, 5876.853078673067, 5931.228940237742, 5963.2844058713235, 5204.245384419238, 5396.757145928443, 5519.917201241985, 5875.970620146872, 5881.529477641779, 5325.414204437431, 5922.496587790086, 5922.674252916045, 5929.0, 5951.642901425928, 5324.307839980777, 5586.77652893896, 5642.210090587239, 5741.0, 5747.8563035924435, 5328.920066198933, 5635.802746904003, 5646.971765141148, 5670.9432905672065, 5683.687397193194, 4785.569298763128, 4847.515248475124, 5582.692951887685, 5715.6568206472275, 5911.7635294587735, 3082.737967272717, 3952.9209415812097, 3985.5815139409942, 3992.1436532011594, 4020.6932746856805, 5418.3497980241045, 5763.077907534775, 5794.62334948227, 5801.360381026707, 5868.23712917318, 5209.270702101633, 5343.385510666168, 5428.945710543002, 5555.1113421021655, 5631.943680563306, 4548.0, 4933.963867587847, 5392.299001129828, 5579.553635709215, 5655.981923253854, 4569.680122391401, 4924.310596516562, 5246.291750613618, 5528.474794894479, 5545.390007099297, 4805.0, 4807.735574543433, 4894.870731231783, 5194.052230413595, 5650.976727450959, 5492.395836458067, 5516.751746171512, 5542.140968149944, 5546.835164615314, 5549.667019978814], "value": 4003.314625184176, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [637.6747858592119, 668.7358493395107, 691.4813889582816, 711.1182134153648, 753.6118898767135, 14816.555503334903, 15036.473723419682, 15190.620234494143, 15381.000234984718, 15388.69222615547, 14463.0, 14533.273336333194, 14593.051451655634, 14596.27018649066, 14653.0, 728.7085165933617, 753.1827966656157, 893.7542175901631, 900.4597241654988, 911.6080085563206, 745.1803016681633, 745.9515131516462, 748.2779118150984, 749.9987516665259, 865.9393842431033, 957.8036502516967, 965.7054598347502, 966.1063516247478, 966.6036924860808, 968.9709308720714, 951.4338968313842, 958.6692591056075, 959.5058544849409, 960.8030353777459, 965.8647789309503, 555.9888802223933, 678.8981652752077, 699.4684040129488, 710.882704353781, 711.5943911970128, 14688.0, 14746.557603271918, 14769.187694676788, 14792.778108328384, 14815.777763333559, 11936.522539098425, 12056.397180141046, 12198.573049943298, 12271.0, 12352.38238088092, 10707.946460267727, 10852.782944341068, 10992.230543861933, 11063.225574209808, 11414.71463213418, 4043.9595604043875, 4091.7954102294766, 4092.774897380643, 4134.235166494208, 4250.192463431969, 74105.0, 76262.47475050468, 76468.0, 76623.0, 78826.99999999943, 9214.677486288081, 9280.489573073546, 9299.256059515223, 9621.0, 9736.318457707892, 21183.046762895705, 21200.0, 21210.469738256357, 21214.25750098739, 21235.68146477804, 7211.495195336276, 7232.963835180791, 7263.418926485869, 7287.526310789888, 7411.184769675331, 3935.8622448214173, 4047.9392809107885, 4091.3453847384276, 4092.508898932147, 4102.261592913304, 3347.196672798511, 3449.844756985942, 3456.740744444177, 3463.0995941055508, 3484.198634314122, 627.9590340920375, 633.8415396150963, 645.4449173710605, 648.1060680906603, 659.5185514574291, 497.50746760707364, 521.6270366687836, 524.3026774390052, 543.4029496189235, 564.0242380681444, 7173.569585824867, 7229.819254518679, 7289.671964761598, 7360.263973602704, 7630.847383052419, 469.99060018799435, 492.6576029659424, 538.1846502548642, 553.1039715660576, 575.8646718021262, 3492.301539692021, 3518.82405879705, 3532.6114127445962, 3610.819459027038, 3688.3176612326806, 74417.0, 84425.31149376978, 85285.57357213175, 90122.54938725395, 90178.54910725405, 78529.60735196287, 80841.0, 81206.59396703097, 81522.0, 82898.75651865227, 66999.66500167469, 67640.32359676439, 73460.63269683617, 74098.77703669007, 74978.25021749822, 6478.611283323015, 6612.90080648781, 6725.831854203489, 6726.192856857208, 7119.67961441726, 44544.0, 46477.76761116308, 47372.52627473681, 48979.02041959212, 49124.50875491199, 62532.0, 65556.67221663862, 65773.67113164403, 65934.67032664806, 67031.3296867025, 9713.465759383302, 9840.901590984286, 9895.752606184902, 9913.107820296207, 10243.897561024593, 9150.0, 10479.68560943173, 10535.051845333957, 10612.371123736399, 10696.518656660313, 9778.07108324708, 10085.092341689284, 10318.329308594917, 11026.228164028762, 11133.276337038067, 10588.152947764007, 10816.4050977197, 10833.458327083457, 10885.510152043336, 11525.135614828754, 10184.745381365525, 10936.507857146607, 11175.72060698489, 11230.213885028297, 11996.820047699117, 8442.873356899774, 9014.0, 9174.67888623895, 9224.077592240723, 9356.0, 9322.580483878377, 9470.796389805771, 9493.335466517554, 9554.952225238829, 9699.272554558294], "value": 6650.8043049120515, "unit": "score"}], "name": "dromaeo_css", "value": 7035.183294819725, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 113.0, 112.0, 113.0, 113.0, 113.0, 113.0, 112.0, 115.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 150.0, 142.0, 146.0, 119.0, 142.0, 136.0, 118.0, 176.0, 140.0], "value": 143.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 183.0, 183.0, 182.0, 181.0, 180.0, 182.0, 184.0, 181.0, 182.0], "value": 182.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 57.0, 61.0, 85.0, 58.0, 57.0, 62.0, 68.0, 102.0, 79.0], "value": 70.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 68.0, 99.0, 70.0, 81.0, 68.0, 92.0, 70.0, 69.0, 97.0], "value": 79.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 91.0, 85.0, 88.0, 84.0, 88.0, 83.0, 87.0, 83.0], "value": 86.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 86.0, 84.0, 86.0, 84.0, 84.0, 88.0, 136.0, 143.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 57.0, 59.0, 60.0, 59.0, 75.0, 58.0, 56.0], "value": 60.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 78.0, 77.0, 75.0, 74.0, 70.0, 73.0, 70.0, 71.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 85.0, 86.0, 86.0, 84.0, 85.0, 85.0, 86.0, 88.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 138.0, 145.0, 145.0, 140.0, 143.0, 139.0, 138.0, 175.0, 138.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 163.0, 166.0, 165.0, 164.0, 163.0, 164.0, 164.0, 163.0, 162.0], "value": 167.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 66.0, 66.0, 66.0, 66.0, 65.0, 65.0, 64.0, 65.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1459.8000000000002, "alertThreshold": 2.0}]} 16:01:29 INFO - Return code: 0 16:01:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:01:29 ERROR - # TBPL SUCCESS # 16:01:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:01:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:01:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:01:29 INFO - 2016-05-25 16:01:29.077 screenresolution[1674:9593] starting screenresolution argv=screenresolution get 16:01:29 INFO - 2016-05-25 16:01:29.080 screenresolution[1674:9593] Display 0: 1600x1200x32@60 16:01:29 INFO - 2016-05-25 16:01:29.086 screenresolution[1675:9596] starting screenresolution argv=screenresolution list 16:01:29 INFO - Available Modes on Display 0 16:01:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:01:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:01:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:01:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:01:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:01:29 INFO - Intel Iris: 16:01:29 INFO - Chipset Model: Intel Iris 16:01:29 INFO - Type: GPU 16:01:29 INFO - Bus: Built-In 16:01:29 INFO - VRAM (Dynamic, Max): 1536 MB 16:01:29 INFO - Vendor: Intel (0x8086) 16:01:29 INFO - Device ID: 0x0a2e 16:01:29 INFO - Revision ID: 0x0009 16:01:29 INFO - Displays: 16:01:29 INFO - Display: 16:01:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:01:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:01:29 INFO - Display Serial Number: 1600x1200 60 16:01:29 INFO - Main Display: Yes 16:01:29 INFO - Mirror: Off 16:01:29 INFO - Online: Yes 16:01:29 INFO - Rotation: Supported 16:01:29 INFO - Return code: 0 16:01:29 INFO - Running post-action listener: _resource_record_post_action 16:01:29 INFO - ##### 16:01:29 INFO - ##### Finished run-tests step (success) 16:01:29 INFO - ##### 16:01:29 INFO - Running post-run listener: _resource_record_post_run 16:01:29 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38008320; Write bytes: 694292992; Read time: 399; Write time: 1860 16:01:29 INFO - TinderboxPrint: CPU usage
53.7% 16:01:29 INFO - TinderboxPrint: I/O read bytes / time
38,008,320 / 399 16:01:29 INFO - TinderboxPrint: I/O write bytes / time
694,292,992 / 1,860 16:01:29 INFO - TinderboxPrint: CPU idle
2,245.8 (46.3%) 16:01:29 INFO - TinderboxPrint: CPU system
966.8 (20.0%) 16:01:29 INFO - TinderboxPrint: CPU user
1,633.1 (33.7%) 16:01:29 INFO - TinderboxPrint: Swap in / out
434,966,528 / 0 16:01:29 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 389134848; Write bytes: 383157760; Read time: 28974; Write time: 758 16:01:29 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 36210176; Write bytes: 293723136; Read time: 380; Write time: 1057 16:01:29 INFO - Running post-run listener: _upload_blobber_files 16:01:29 INFO - Blob upload gear active. 16:01:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:01: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 16:01: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'] 16:01: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 16:01:30 INFO - (blobuploader) - INFO - Open directory for files ... 16:01:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:01:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:01:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:01:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:01:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:01:30 INFO - (blobuploader) - INFO - Done attempting. 16:01:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:01:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:01:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:01:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:01:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:01:32 INFO - (blobuploader) - INFO - Done attempting. 16:01:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:01:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:01:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:01:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:01:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:01:35 INFO - (blobuploader) - INFO - Done attempting. 16:01:35 INFO - (blobuploader) - INFO - Iteration through files over. 16:01:35 INFO - Return code: 0 16:01:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:01:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:01:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c120a3c33c2094b48fbf752b82983cd4af5ece73fd52ec802a9c336f6a6fe1db4c2564c0fe4b31a20a4785d289efd6069121fd9b0cf9921aa041f620b5327783", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ed315ad7f3b0e66cdc1f62fbc80913180db1206109fa17b7098cf69c13db6b6a8989088f51d2b3b0f723b488023b928187a2f34d19996fd3be23ae15adb6385", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6cdca2fd6c454773fee6a137b37e5d1ea5455562ff967ec5c40e4bbd74864b7e841ba5949900b3ed56838e922465833d29a41cebe04b5dcc9f77f42b0f14dc09"} 16:01:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:01:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:01:35 INFO - Contents: 16:01:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c120a3c33c2094b48fbf752b82983cd4af5ece73fd52ec802a9c336f6a6fe1db4c2564c0fe4b31a20a4785d289efd6069121fd9b0cf9921aa041f620b5327783", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ed315ad7f3b0e66cdc1f62fbc80913180db1206109fa17b7098cf69c13db6b6a8989088f51d2b3b0f723b488023b928187a2f34d19996fd3be23ae15adb6385", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6cdca2fd6c454773fee6a137b37e5d1ea5455562ff967ec5c40e4bbd74864b7e841ba5949900b3ed56838e922465833d29a41cebe04b5dcc9f77f42b0f14dc09"} 16:01:35 INFO - Running post-run listener: copy_logs_to_upload_dir 16:01:35 INFO - Copying logs to upload dir... 16:01:35 INFO - mkdir: /builds/slave/test/build/upload/logs 16:01:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.840352 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-25 16:01:35.440843) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 16:01:35.444407) ========= 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.vIG2C8zznB/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.Em5W69yZWc/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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/c120a3c33c2094b48fbf752b82983cd4af5ece73fd52ec802a9c336f6a6fe1db4c2564c0fe4b31a20a4785d289efd6069121fd9b0cf9921aa041f620b5327783", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ed315ad7f3b0e66cdc1f62fbc80913180db1206109fa17b7098cf69c13db6b6a8989088f51d2b3b0f723b488023b928187a2f34d19996fd3be23ae15adb6385", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6cdca2fd6c454773fee6a137b37e5d1ea5455562ff967ec5c40e4bbd74864b7e841ba5949900b3ed56838e922465833d29a41cebe04b5dcc9f77f42b0f14dc09"} build_url:https://queue.taskcluster.net/v1/task/GIKY64HMQIe4lBwT89m40w/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010861 build_url: 'https://queue.taskcluster.net/v1/task/GIKY64HMQIe4lBwT89m40w/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/c120a3c33c2094b48fbf752b82983cd4af5ece73fd52ec802a9c336f6a6fe1db4c2564c0fe4b31a20a4785d289efd6069121fd9b0cf9921aa041f620b5327783", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8ed315ad7f3b0e66cdc1f62fbc80913180db1206109fa17b7098cf69c13db6b6a8989088f51d2b3b0f723b488023b928187a2f34d19996fd3be23ae15adb6385", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6cdca2fd6c454773fee6a137b37e5d1ea5455562ff967ec5c40e4bbd74864b7e841ba5949900b3ed56838e922465833d29a41cebe04b5dcc9f77f42b0f14dc09"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 16:01:35.475544) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 16:01:35.475930) ========= 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.vIG2C8zznB/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.Em5W69yZWc/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.005044 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 16:01:35.525153) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 16:01:35.525541) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 16:01:35.526078) ========= ========= Total master_lag: 0.28 =========