builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0132 starttime: 1463628319.4 results: success (0) buildid: 20160518193138 builduid: 618ed07f7c544d299f66c01f6134d131 revision: cee3b4a96f41e1bb792f267728f9f3083f08349a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 20:25:19.396069) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-18 20:25:19.396473) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 20:25:19.396764) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-18 20:25:19.414859) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:25:19.415229) ========= 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.xnLqKhodRx/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.CB8CiSxLRw/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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-18 20:25:19-- 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% 163M=0s 2016-05-18 20:25:19 (163 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118988 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:25:19.551033) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:25:19.551347) ========= 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.xnLqKhodRx/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.CB8CiSxLRw/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.020610 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:25:19.599358) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:25:19.599722) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev cee3b4a96f41e1bb792f267728f9f3083f08349a --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 cee3b4a96f41e1bb792f267728f9f3083f08349a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xnLqKhodRx/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.CB8CiSxLRw/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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-18 20:25:19,675 truncating revision to first 12 chars 2016-05-18 20:25:19,675 Setting DEBUG logging. 2016-05-18 20:25:19,675 attempt 1/10 2016-05-18 20:25:19,675 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/cee3b4a96f41?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-18 20:25:20,371 unpacking tar archive at: fx-team-cee3b4a96f41/testing/mozharness/ program finished with exit code 0 elapsedTime=0.951541 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:25:20.568486) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 20:25:20.568819) ========= script_repo_revision: cee3b4a96f41e1bb792f267728f9f3083f08349a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-18 20:25:20.569229) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 20:25:20.569504) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-18 20:25:20.583551) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-18 20:25:20.584107) ========= /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.xnLqKhodRx/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.CB8CiSxLRw/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 20:25:20 INFO - MultiFileLogger online at 20160518 20:25:20 in /builds/slave/test 20:25:20 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 20:25:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:25:20 INFO - {'append_to_log': False, 20:25:20 INFO - 'base_work_dir': '/builds/slave/test', 20:25:20 INFO - 'blob_upload_branch': 'Fx-Team', 20:25:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:25:20 INFO - 'branch': 'Fx-Team', 20:25:20 INFO - 'buildbot_json_path': 'buildprops.json', 20:25:20 INFO - 'config_files': ('talos/mac_config.py',), 20:25:20 INFO - 'default_actions': ('clobber', 20:25:20 INFO - 'read-buildbot-config', 20:25:20 INFO - 'download-and-extract', 20:25:20 INFO - 'populate-webroot', 20:25:20 INFO - 'create-virtualenv', 20:25:20 INFO - 'install', 20:25:20 INFO - 'run-tests'), 20:25:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:25:20 INFO - 'download_minidump_stackwalk': True, 20:25:20 INFO - 'download_symbols': 'ondemand', 20:25:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:25:20 INFO - 'tooltool.py': '/tools/tooltool.py', 20:25:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:25:20 INFO - '/tools/misc-python/virtualenv.py')}, 20:25:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:25:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:25:20 INFO - 'installer_path': 'installer.exe', 20:25:20 INFO - 'log_level': 'info', 20:25:20 INFO - 'log_name': 'talos', 20:25:20 INFO - 'log_to_console': True, 20:25:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:25:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:25:20 INFO - 'opt_config_files': (), 20:25:20 INFO - 'pip_index': False, 20:25:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:25:20 INFO - 'cmd': ('bash', 20:25:20 INFO - '-c', 20:25:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:25:20 INFO - 'enabled': True, 20:25:20 INFO - 'halt_on_failure': False, 20:25:20 INFO - 'name': 'check_screen_resolution'},), 20:25:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:25:20 INFO - 'cmd': ('bash', 20:25:20 INFO - '-c', 20:25:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:25:20 INFO - 'enabled': True, 20:25:20 INFO - 'halt_on_failure': False, 20:25:20 INFO - 'name': 'check_screen_resolution'},), 20:25:20 INFO - 'run_cmd_checks_enabled': True, 20:25:20 INFO - 'sps_profile': False, 20:25:20 INFO - 'sps_profile_interval': 0, 20:25:20 INFO - 'suite': 'dromaeojs', 20:25:20 INFO - 'system_bits': '32', 20:25:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:25:20 INFO - 'title': 't-yosemite-r7-0132', 20:25:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:25:20 INFO - 'use_talos_json': True, 20:25:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:25:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:25:20 INFO - 'work_dir': 'build'} 20:25:20 INFO - ##### 20:25:20 INFO - ##### Running clobber step. 20:25:20 INFO - ##### 20:25:20 INFO - Running pre-action listener: _resource_record_pre_action 20:25:20 INFO - Running main action method: clobber 20:25:20 INFO - rmtree: /builds/slave/test/build 20:25:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:25:22 INFO - Running post-action listener: _resource_record_post_action 20:25:22 INFO - ##### 20:25:22 INFO - ##### Finished clobber step (success) 20:25:22 INFO - ##### 20:25:22 INFO - ##### 20:25:22 INFO - ##### Running read-buildbot-config step. 20:25:22 INFO - ##### 20:25:22 INFO - Running pre-action listener: _resource_record_pre_action 20:25:22 INFO - Running main action method: read_buildbot_config 20:25:22 INFO - Using buildbot properties: 20:25:22 INFO - { 20:25:22 INFO - "project": "", 20:25:22 INFO - "product": "firefox", 20:25:22 INFO - "script_repo_revision": "production", 20:25:22 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 20:25:22 INFO - "repository": "", 20:25:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 20:25:22 INFO - "buildid": "20160518193138", 20:25:22 INFO - "pgo_build": "False", 20:25:22 INFO - "basedir": "/builds/slave/test", 20:25:22 INFO - "buildnumber": 13, 20:25:22 INFO - "slavename": "t-yosemite-r7-0132", 20:25:22 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 20:25:22 INFO - "platform": "yosemite_r7", 20:25:22 INFO - "scheduler": "tests-fx-team-macosx64-talos", 20:25:22 INFO - "branch": "fx-team", 20:25:22 INFO - "repo_path": "integration/fx-team", 20:25:22 INFO - "revision": "cee3b4a96f41e1bb792f267728f9f3083f08349a", 20:25:22 INFO - "stage_platform": "macosx64", 20:25:22 INFO - "builduid": "618ed07f7c544d299f66c01f6134d131", 20:25:22 INFO - "slavebuilddir": "test" 20:25:22 INFO - } 20:25:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 20:25:22 INFO - Running post-action listener: _resource_record_post_action 20:25:22 INFO - ##### 20:25:22 INFO - ##### Finished read-buildbot-config step (success) 20:25:22 INFO - ##### 20:25:22 INFO - ##### 20:25:22 INFO - ##### Running download-and-extract step. 20:25:22 INFO - ##### 20:25:22 INFO - Running pre-action listener: _resource_record_pre_action 20:25:22 INFO - Running main action method: download_and_extract 20:25:22 INFO - mkdir: /builds/slave/test/build/tests 20:25:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:25:22 INFO - https://queue.taskcluster.net/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 20:25:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 20:25:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 20:25:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/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 20:25:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/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 20:25:24 INFO - Downloaded 1199 bytes. 20:25:24 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 20:25:24 INFO - Using the following test package requirements: 20:25:24 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 20:25:24 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:25:24 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 20:25:24 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:25:24 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 20:25:24 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:25:24 INFO - u'jsshell-mac.zip'], 20:25:24 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:25:24 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 20:25:24 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 20:25:24 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:25:24 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 20:25:24 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:25:24 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 20:25:24 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:25:24 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 20:25:24 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 20:25:24 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 20:25:24 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:25:24 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')]} 20:25:24 INFO - https://queue.taskcluster.net/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:25:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:25:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:25:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/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 20:25:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/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 20:25:25 INFO - Downloaded 18628926 bytes. 20:25:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:25:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 20:25:28 INFO - Return code: 0 20:25:28 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 20:25:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:25:28 INFO - https://queue.taskcluster.net/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:25:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:25:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 20:25:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/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 20:25:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/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 20:25:29 INFO - Downloaded 18628926 bytes. 20:25:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:25:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 20:25:33 INFO - Return code: 0 20:25:33 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')]} 20:25:33 INFO - https://queue.taskcluster.net/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 20:25:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:25:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 20:25:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/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 20:25:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/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 20:25:33 INFO - Downloaded 10840194 bytes. 20:25:33 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'] 20:25:33 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 20:25:34 INFO - Return code: 0 20:25:34 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')]} 20:25:34 INFO - https://queue.taskcluster.net/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 20:25:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:25:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:25:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 20:25:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VuLPxXqwTzaarGrh_Vf60g/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 20:25:37 INFO - Downloaded 110311089 bytes. 20:25:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:25:37 INFO - mkdir: /builds/slave/test/properties 20:25:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:25:37 INFO - Writing to file /builds/slave/test/properties/build_url 20:25:37 INFO - Contents: 20:25:37 INFO - build_url:https://queue.taskcluster.net/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 20:25:38 INFO - Running post-action listener: _resource_record_post_action 20:25:38 INFO - Running post-action listener: set_extra_try_arguments 20:25:38 INFO - ##### 20:25:38 INFO - ##### Finished download-and-extract step (success) 20:25:38 INFO - ##### 20:25:38 INFO - ##### 20:25:38 INFO - ##### Running populate-webroot step. 20:25:38 INFO - ##### 20:25:38 INFO - Running pre-action listener: _resource_record_pre_action 20:25:38 INFO - Running main action method: populate_webroot 20:25:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:25:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:25:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:25:38 INFO - '--tppagecycles', 20:25:38 INFO - '7'], 20:25:38 INFO - 'tests': ['tsvgm']}}, 20:25:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:25:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:25:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:25:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:25:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:25:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:25:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:25:38 INFO - 'talos_options': ['--disable-e10s'], 20:25:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:25:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:25:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:25:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:25:38 INFO - 'talos_options': ['--disable-e10s'], 20:25:38 INFO - 'tests': ['damp', 'tps']}, 20:25:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:25:38 INFO - 'tests': ['damp', 'tps']}, 20:25:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:25:38 INFO - 'tests': ['dromaeo_dom']}, 20:25:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:25:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:25:38 INFO - 'tests': ['a11yr', 20:25:38 INFO - 'ts_paint', 20:25:38 INFO - 'tpaint', 20:25:38 INFO - 'sessionrestore', 20:25:38 INFO - 'sessionrestore_no_auto_restore', 20:25:38 INFO - 'tabpaint']}, 20:25:38 INFO - 'other-e10s': {'tests': ['a11yr', 20:25:38 INFO - 'ts_paint', 20:25:38 INFO - 'tpaint', 20:25:38 INFO - 'sessionrestore', 20:25:38 INFO - 'sessionrestore_no_auto_restore', 20:25:38 INFO - 'tabpaint']}, 20:25:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:25:38 INFO - 'ts_paint', 20:25:38 INFO - 'tpaint', 20:25:38 INFO - 'sessionrestore', 20:25:38 INFO - 'sessionrestore_no_auto_restore', 20:25:38 INFO - 'tabpaint']}, 20:25:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:25:38 INFO - 'ts_paint', 20:25:38 INFO - 'tpaint', 20:25:38 INFO - 'sessionrestore', 20:25:38 INFO - 'sessionrestore_no_auto_restore', 20:25:38 INFO - 'tabpaint']}, 20:25:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:25:38 INFO - 'tests': ['a11yr', 20:25:38 INFO - 'ts_paint', 20:25:38 INFO - 'tpaint', 20:25:38 INFO - 'sessionrestore', 20:25:38 INFO - 'sessionrestore_no_auto_restore', 20:25:38 INFO - 'tabpaint']}, 20:25:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:25:38 INFO - 'tests': ['a11yr', 20:25:38 INFO - 'ts_paint', 20:25:38 INFO - 'tpaint', 20:25:38 INFO - 'sessionrestore', 20:25:38 INFO - 'sessionrestore_no_auto_restore', 20:25:38 INFO - 'tabpaint']}, 20:25:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:25:38 INFO - 'tests': ['tsvgx', 20:25:38 INFO - 'tsvgr_opacity', 20:25:38 INFO - 'tart', 20:25:38 INFO - 'tscrollx', 20:25:38 INFO - 'cart']}, 20:25:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:25:38 INFO - 'tsvgr_opacity', 20:25:38 INFO - 'tart', 20:25:38 INFO - 'tscrollx', 20:25:38 INFO - 'cart']}, 20:25:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:25:38 INFO - 'talos_options': ['--disable-e10s'], 20:25:38 INFO - 'tests': ['tp5o']}, 20:25:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:25:38 INFO - 'tests': ['tp5o']}, 20:25:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:25:38 INFO - 'talos_options': ['--disable-e10s', 20:25:38 INFO - '--xperf_path', 20:25:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:25:38 INFO - 'tests': ['tp5n']}, 20:25:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:25:38 INFO - 'talos_options': ['--xperf_path', 20:25:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:25:38 INFO - 'tests': ['tp5n']}}, 20:25:38 INFO - 'talos.zip': {'path': '', 20:25:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:25:38 INFO - Running post-action listener: _resource_record_post_action 20:25:38 INFO - ##### 20:25:38 INFO - ##### Finished populate-webroot step (success) 20:25:38 INFO - ##### 20:25:38 INFO - ##### 20:25:38 INFO - ##### Running create-virtualenv step. 20:25:38 INFO - ##### 20:25:38 INFO - Running pre-action listener: _resource_record_pre_action 20:25:38 INFO - Running main action method: create_virtualenv 20:25:38 INFO - Creating virtualenv /builds/slave/test/build/venv 20:25:38 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 20:25:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:25:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:25:38 INFO - Using real prefix '/tools/python27' 20:25:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:25:39 INFO - Installing distribute.............................................................................................................................................................................................done. 20:25:41 INFO - Installing pip.................done. 20:25:41 INFO - Return code: 0 20:25:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:25:41 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')]} 20:25:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:41 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')]} 20:25:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:41 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 0x10b897a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b89d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d3a49f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b73bd30>, '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 0x10b55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b732b20>, '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.xnLqKhodRx/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.CB8CiSxLRw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 20:25:41 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 20:25:41 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 20:25:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnLqKhodRx/Render', 20:25:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:41 INFO - 'HOME': '/Users/cltbld', 20:25:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:41 INFO - 'LOGNAME': 'cltbld', 20:25:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:41 INFO - 'MOZ_NO_REMOTE': '1', 20:25:41 INFO - 'NO_EM_RESTART': '1', 20:25:41 INFO - 'PAGER': '/bin/cat', 20:25:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:41 INFO - 'PWD': '/builds/slave/test', 20:25:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:41 INFO - 'SHELL': '/bin/bash', 20:25:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CB8CiSxLRw/Listeners', 20:25:41 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 20:25:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:41 INFO - 'USER': 'cltbld', 20:25:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:41 INFO - 'XPC_FLAGS': '0x0', 20:25:41 INFO - 'XPC_SERVICE_NAME': '0', 20:25:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:42 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 20:25:42 INFO - Cleaning up... 20:25:42 INFO - Return code: 0 20:25:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:25:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:25:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:25:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b897a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b89d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d3a49f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b73bd30>, '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 0x10b55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b732b20>, '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.xnLqKhodRx/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.CB8CiSxLRw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 20:25:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:25:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:25:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnLqKhodRx/Render', 20:25:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:42 INFO - 'HOME': '/Users/cltbld', 20:25:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:42 INFO - 'LOGNAME': 'cltbld', 20:25:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:42 INFO - 'MOZ_NO_REMOTE': '1', 20:25:42 INFO - 'NO_EM_RESTART': '1', 20:25:42 INFO - 'PAGER': '/bin/cat', 20:25:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:42 INFO - 'PWD': '/builds/slave/test', 20:25:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:42 INFO - 'SHELL': '/bin/bash', 20:25:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CB8CiSxLRw/Listeners', 20:25:42 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 20:25:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:42 INFO - 'USER': 'cltbld', 20:25:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:42 INFO - 'XPC_FLAGS': '0x0', 20:25:42 INFO - 'XPC_SERVICE_NAME': '0', 20:25:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:42 INFO - Downloading/unpacking psutil>=3.1.1 20:25:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:25:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:25:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:25:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:25:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:25:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:25:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:25:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:25:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:25:45 INFO - Installing collected packages: psutil 20:25:45 INFO - Running setup.py install for psutil 20:25:45 INFO - building 'psutil._psutil_osx' extension 20:25:45 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 20:25:45 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 20:25:45 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 20:25:45 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 20:25:45 INFO - building 'psutil._psutil_posix' extension 20:25:45 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 20:25:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:25:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:25:45 INFO - ^ 20:25:46 INFO - 1 warning generated. 20:25:46 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 20:25:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:25:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:25:46 INFO - Successfully installed psutil 20:25:46 INFO - Cleaning up... 20:25:46 INFO - Return code: 0 20:25:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:25:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:25:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:25:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:46 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 0x10b897a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b89d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d3a49f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b73bd30>, '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 0x10b55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b732b20>, '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.xnLqKhodRx/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.CB8CiSxLRw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 20:25:46 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 20:25:46 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 20:25:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnLqKhodRx/Render', 20:25:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:46 INFO - 'HOME': '/Users/cltbld', 20:25:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:46 INFO - 'LOGNAME': 'cltbld', 20:25:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:46 INFO - 'MOZ_NO_REMOTE': '1', 20:25:46 INFO - 'NO_EM_RESTART': '1', 20:25:46 INFO - 'PAGER': '/bin/cat', 20:25:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:46 INFO - 'PWD': '/builds/slave/test', 20:25:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:46 INFO - 'SHELL': '/bin/bash', 20:25:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CB8CiSxLRw/Listeners', 20:25:46 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 20:25:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:46 INFO - 'USER': 'cltbld', 20:25:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:46 INFO - 'XPC_FLAGS': '0x0', 20:25:46 INFO - 'XPC_SERVICE_NAME': '0', 20:25:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:25:46 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 20:25:46 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 20:25:46 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 20:25:46 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 20:25:46 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 20:25:46 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 20:25:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:25:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:25:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:25:49 INFO - Installing collected packages: mozsystemmonitor 20:25:49 INFO - Running setup.py install for mozsystemmonitor 20:25:49 INFO - Successfully installed mozsystemmonitor 20:25:49 INFO - Cleaning up... 20:25:49 INFO - Return code: 0 20:25:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:25:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:25:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:49 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 0x10b897a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b89d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d3a49f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b73bd30>, '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 0x10b55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b732b20>, '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.xnLqKhodRx/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.CB8CiSxLRw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 20:25:49 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 20:25:49 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 20:25:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnLqKhodRx/Render', 20:25:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:49 INFO - 'HOME': '/Users/cltbld', 20:25:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:49 INFO - 'LOGNAME': 'cltbld', 20:25:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:49 INFO - 'MOZ_NO_REMOTE': '1', 20:25:49 INFO - 'NO_EM_RESTART': '1', 20:25:49 INFO - 'PAGER': '/bin/cat', 20:25:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:49 INFO - 'PWD': '/builds/slave/test', 20:25:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:49 INFO - 'SHELL': '/bin/bash', 20:25:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CB8CiSxLRw/Listeners', 20:25:49 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 20:25:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:49 INFO - 'USER': 'cltbld', 20:25:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:49 INFO - 'XPC_FLAGS': '0x0', 20:25:49 INFO - 'XPC_SERVICE_NAME': '0', 20:25:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:49 INFO - Downloading/unpacking blobuploader==1.2.4 20:25:49 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 20:25:49 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 20:25:49 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 20:25:49 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 20:25:49 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 20:25:49 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 20:25:51 INFO - Downloading blobuploader-1.2.4.tar.gz 20:25:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:25:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:25: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 20:25: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 20:25: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 20:25: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 20:25: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 20:25: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 20:25:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:25:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:25: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 20:25: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 20:25: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 20:25: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 20:25: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 20:25: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 20:25:53 INFO - Downloading docopt-0.6.1.tar.gz 20:25:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:25:53 INFO - Installing collected packages: blobuploader, requests, docopt 20:25:53 INFO - Running setup.py install for blobuploader 20:25:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:25:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:25:53 INFO - Running setup.py install for requests 20:25:53 INFO - Running setup.py install for docopt 20:25:54 INFO - Successfully installed blobuploader requests docopt 20:25:54 INFO - Cleaning up... 20:25:54 INFO - Return code: 0 20:25:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:25:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:25:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:54 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 0x10b897a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b89d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d3a49f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b73bd30>, '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 0x10b55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b732b20>, '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.xnLqKhodRx/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.CB8CiSxLRw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 20:25:54 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 20:25:54 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 20:25:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnLqKhodRx/Render', 20:25:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:54 INFO - 'HOME': '/Users/cltbld', 20:25:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:54 INFO - 'LOGNAME': 'cltbld', 20:25:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:54 INFO - 'MOZ_NO_REMOTE': '1', 20:25:54 INFO - 'NO_EM_RESTART': '1', 20:25:54 INFO - 'PAGER': '/bin/cat', 20:25:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:54 INFO - 'PWD': '/builds/slave/test', 20:25:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:54 INFO - 'SHELL': '/bin/bash', 20:25:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CB8CiSxLRw/Listeners', 20:25:54 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 20:25:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:54 INFO - 'USER': 'cltbld', 20:25:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:54 INFO - 'XPC_FLAGS': '0x0', 20:25:54 INFO - 'XPC_SERVICE_NAME': '0', 20:25:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:25:54 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-EXKA1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:25:54 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-mt8EdN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:25:54 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-HqCDZs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:25:54 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-rLpxLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:25:54 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-MTLtdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:25:54 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-4VwzFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:25:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:25:54 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-Yef8dW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:25:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-nCbjiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:25:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-31SHac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:25:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-YmdJxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:25:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-fYnCAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:25:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-jQVldW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:25:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-WFVJR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:25:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-iBPKA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:25:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-5Hpc7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:25:55 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-h3Aml1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:25:56 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-bmYA3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:25:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:25:56 INFO - Running setup.py install for manifestparser 20:25:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:25:56 INFO - Running setup.py install for mozcrash 20:25:56 INFO - Running setup.py install for mozdebug 20:25:56 INFO - Running setup.py install for mozdevice 20:25:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:25:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:25:56 INFO - Running setup.py install for mozfile 20:25:56 INFO - Running setup.py install for mozhttpd 20:25:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:25:57 INFO - Running setup.py install for mozinfo 20:25:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:25:57 INFO - Running setup.py install for mozInstall 20:25:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:25:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:25:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:25:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:25:57 INFO - Running setup.py install for mozleak 20:25:57 INFO - Running setup.py install for mozlog 20:25:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:25:57 INFO - Running setup.py install for moznetwork 20:25:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:25:57 INFO - Running setup.py install for mozprocess 20:25:57 INFO - Running setup.py install for mozprofile 20:25:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:25:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:25:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:25:58 INFO - Running setup.py install for mozrunner 20:25:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:25:58 INFO - Running setup.py install for mozscreenshot 20:25:58 INFO - Running setup.py install for moztest 20:25:58 INFO - Running setup.py install for mozversion 20:25:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:25:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:25:58 INFO - Cleaning up... 20:25:58 INFO - Return code: 0 20:25:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:25:58 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')]} 20:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:58 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')]} 20:25:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:58 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 0x10b897a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b89d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d3a49f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b73bd30>, '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 0x10b55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b732b20>, '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.xnLqKhodRx/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.CB8CiSxLRw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 20:25:58 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 20:25:58 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 20:25:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnLqKhodRx/Render', 20:25:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:58 INFO - 'HOME': '/Users/cltbld', 20:25:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:58 INFO - 'LOGNAME': 'cltbld', 20:25:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:58 INFO - 'MOZ_NO_REMOTE': '1', 20:25:58 INFO - 'NO_EM_RESTART': '1', 20:25:58 INFO - 'PAGER': '/bin/cat', 20:25:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:58 INFO - 'PWD': '/builds/slave/test', 20:25:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:58 INFO - 'SHELL': '/bin/bash', 20:25:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CB8CiSxLRw/Listeners', 20:25:58 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 20:25:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:58 INFO - 'USER': 'cltbld', 20:25:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:58 INFO - 'XPC_FLAGS': '0x0', 20:25:58 INFO - 'XPC_SERVICE_NAME': '0', 20:25:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:25:59 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-qLVbM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:25:59 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)) 20:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:25:59 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-lZwtYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:25:59 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)) 20:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:25:59 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-0PCj9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:25:59 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)) 20:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:25:59 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-mtlYW_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:25:59 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)) 20:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:25:59 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-_fdFtt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:25:59 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)) 20:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:25:59 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-96ZGQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:25:59 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)) 20:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:25:59 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-85V9MN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:25:59 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)) 20:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:25:59 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-u32WgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:25:59 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)) 20:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:25:59 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-bOmweM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:25:59 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)) 20:25:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:25:59 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-YxKrCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:26:00 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)) 20:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:26:00 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-DaUgbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:26:00 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)) 20:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:26:00 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-aAO2JD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:26:00 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)) 20:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:26:00 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-iFHsWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:26:00 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)) 20:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:26:00 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-x8L43L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:26:00 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)) 20:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:26:00 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-PGWtkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:26:00 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)) 20:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:26:00 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-9RFUIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:26:00 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)) 20:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:26:00 INFO - Running setup.py (path:/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/pip-QocyZl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:26:00 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)) 20:26:00 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)) 20:26:00 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)) 20:26:00 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)) 20:26:00 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)) 20:26:00 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)) 20:26:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:26:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:26:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:26:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:26:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:26:03 INFO - Downloading blessings-1.6.tar.gz 20:26:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:26:03 INFO - Installing collected packages: blessings 20:26:03 INFO - Running setup.py install for blessings 20:26:03 INFO - Successfully installed blessings 20:26:03 INFO - Cleaning up... 20:26:03 INFO - Return code: 0 20:26:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:26:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:26:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:26:03 INFO - Reading from file tmpfile_stdout 20:26:03 INFO - Current package versions: 20:26:03 INFO - blessings == 1.6 20:26:03 INFO - blobuploader == 1.2.4 20:26:03 INFO - docopt == 0.6.1 20:26:03 INFO - manifestparser == 1.1 20:26:03 INFO - mozInstall == 1.12 20:26:03 INFO - mozcrash == 0.17 20:26:03 INFO - mozdebug == 0.1 20:26:03 INFO - mozdevice == 0.48 20:26:03 INFO - mozfile == 1.2 20:26:03 INFO - mozhttpd == 0.7 20:26:03 INFO - mozinfo == 0.9 20:26:03 INFO - mozleak == 0.1 20:26:03 INFO - mozlog == 3.2 20:26:03 INFO - moznetwork == 0.27 20:26:03 INFO - mozprocess == 0.22 20:26:03 INFO - mozprofile == 0.28 20:26:03 INFO - mozrunner == 6.11 20:26:03 INFO - mozscreenshot == 0.1 20:26:03 INFO - mozsystemmonitor == 0.3 20:26:03 INFO - moztest == 0.7 20:26:03 INFO - mozversion == 1.4 20:26:03 INFO - psutil == 3.1.1 20:26:03 INFO - requests == 1.2.3 20:26:03 INFO - wsgiref == 0.1.2 20:26:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:26:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:26:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:26:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:26:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:26:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:26:03 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 0x10b897a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b89d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d3a49f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b73bd30>, '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 0x10b55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b732b20>, '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.xnLqKhodRx/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.CB8CiSxLRw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 20:26:03 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 20:26:03 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 20:26:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnLqKhodRx/Render', 20:26:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:26:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:26:03 INFO - 'HOME': '/Users/cltbld', 20:26:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:26:03 INFO - 'LOGNAME': 'cltbld', 20:26:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:26:03 INFO - 'MOZ_NO_REMOTE': '1', 20:26:03 INFO - 'NO_EM_RESTART': '1', 20:26:03 INFO - 'PAGER': '/bin/cat', 20:26:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:26:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:26:03 INFO - 'PWD': '/builds/slave/test', 20:26:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:26:03 INFO - 'SHELL': '/bin/bash', 20:26:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CB8CiSxLRw/Listeners', 20:26:03 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 20:26:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:26:03 INFO - 'USER': 'cltbld', 20:26:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:26:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:26:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:26:03 INFO - 'XPC_FLAGS': '0x0', 20:26:03 INFO - 'XPC_SERVICE_NAME': '0', 20:26:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:26:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:26:04 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)) 20:26:04 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)) 20:26:04 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)) 20:26:04 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)) 20:26:04 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)) 20:26:04 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)) 20:26:04 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)) 20:26:04 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)) 20:26:04 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)) 20:26:04 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)) 20:26:04 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)) 20:26:04 INFO - Cleaning up... 20:26:04 INFO - Return code: 0 20:26:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:26: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')]} 20:26:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:26:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:26: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')]} 20:26:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:26:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:26:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b897a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b89d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d3a49f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b73bd30>, '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 0x10b55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b732b20>, '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.xnLqKhodRx/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.CB8CiSxLRw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 20:26:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:26:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:26:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnLqKhodRx/Render', 20:26:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:26:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:26:04 INFO - 'HOME': '/Users/cltbld', 20:26:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:26:04 INFO - 'LOGNAME': 'cltbld', 20:26:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:26:04 INFO - 'MOZ_NO_REMOTE': '1', 20:26:04 INFO - 'NO_EM_RESTART': '1', 20:26:04 INFO - 'PAGER': '/bin/cat', 20:26:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:26:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:26:04 INFO - 'PWD': '/builds/slave/test', 20:26:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:26:04 INFO - 'SHELL': '/bin/bash', 20:26:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CB8CiSxLRw/Listeners', 20:26:04 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 20:26:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:26:04 INFO - 'USER': 'cltbld', 20:26:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:26:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:26:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:26:04 INFO - 'XPC_FLAGS': '0x0', 20:26:04 INFO - 'XPC_SERVICE_NAME': '0', 20:26:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:26:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:26:04 INFO - Downloading/unpacking jsonschema 20:26:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:26:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:26:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:26:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:26:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:26:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:26:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:26:06 INFO - Downloading/unpacking functools32 (from jsonschema) 20:26: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 20:26: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 20:26: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 20:26: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 20:26: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 20:26: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 20:26:07 INFO - Downloading functools32-3.2.3-2.tar.gz 20:26:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:26:07 INFO - warning: no files found matching '*.txt' 20:26:07 INFO - no previously-included directories found matching 'build' 20:26:07 INFO - no previously-included directories found matching 'dist' 20:26:07 INFO - no previously-included directories found matching '.git*' 20:26:07 INFO - Installing collected packages: jsonschema, functools32 20:26:07 INFO - Running setup.py install for functools32 20:26:07 INFO - warning: no files found matching '*.txt' 20:26:07 INFO - no previously-included directories found matching 'build' 20:26:07 INFO - no previously-included directories found matching 'dist' 20:26:07 INFO - no previously-included directories found matching '.git*' 20:26:07 INFO - Successfully installed jsonschema functools32 20:26:07 INFO - Cleaning up... 20:26:07 INFO - Return code: 0 20:26:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:26:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:26:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:26:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:26:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:26:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:26:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b897a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b89d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9d3a49f850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b73bd30>, '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 0x10b55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b732b20>, '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.xnLqKhodRx/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.CB8CiSxLRw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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 20:26:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 20:26:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 20:26:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnLqKhodRx/Render', 20:26:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:26:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:26:07 INFO - 'HOME': '/Users/cltbld', 20:26:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:26:07 INFO - 'LOGNAME': 'cltbld', 20:26:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:26:07 INFO - 'MOZ_NO_REMOTE': '1', 20:26:07 INFO - 'NO_EM_RESTART': '1', 20:26:07 INFO - 'PAGER': '/bin/cat', 20:26:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:26:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:26:07 INFO - 'PWD': '/builds/slave/test', 20:26:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:26:07 INFO - 'SHELL': '/bin/bash', 20:26:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CB8CiSxLRw/Listeners', 20:26:07 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 20:26:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:26:07 INFO - 'USER': 'cltbld', 20:26:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:26:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:26:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:26:07 INFO - 'XPC_FLAGS': '0x0', 20:26:07 INFO - 'XPC_SERVICE_NAME': '0', 20:26:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:26:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:26:07 INFO - Downloading/unpacking flake8 20:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:26:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:26:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:26:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:26:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:26:10 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:26:10 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:26: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 20:26: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 20:26: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 20:26: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 20:26: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 20:26: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 20:26:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:26:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:26: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 20:26: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 20:26: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 20:26: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 20:26: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 20:26: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 20:26:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:26:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:26:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:26:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:26:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:26:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:26:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:26:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:26:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:26:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:26:11 INFO - no previously-included directories found matching 'docs/_build' 20:26:11 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:26:11 INFO - Running setup.py install for pep8 20:26:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:26:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:26:11 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:26:11 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:26:11 INFO - no previously-included directories found matching 'docs/_build' 20:26:11 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:26:11 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:26:11 INFO - Cleaning up... 20:26:12 INFO - Return code: 0 20:26:12 INFO - Running post-action listener: _resource_record_post_action 20:26:12 INFO - Running post-action listener: _start_resource_monitoring 20:26:12 INFO - Starting resource monitoring. 20:26:12 INFO - ##### 20:26:12 INFO - ##### Finished create-virtualenv step (success) 20:26:12 INFO - ##### 20:26:12 INFO - ##### 20:26:12 INFO - ##### Running install step. 20:26:12 INFO - ##### 20:26:12 INFO - Running pre-action listener: _resource_record_pre_action 20:26:12 INFO - Running main action method: install 20:26:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:26:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:26:12 INFO - Reading from file tmpfile_stdout 20:26:12 INFO - Detecting whether we're running mozinstall >=1.0... 20:26:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:26:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:26:12 INFO - Reading from file tmpfile_stdout 20:26:12 INFO - Output received: 20:26:12 INFO - Usage: mozinstall [options] installer 20:26:12 INFO - Options: 20:26:12 INFO - -h, --help show this help message and exit 20:26:12 INFO - -d DEST, --destination=DEST 20:26:12 INFO - Directory to install application into. [default: 20:26:12 INFO - "/builds/slave/test"] 20:26:12 INFO - --app=APP Application being installed. [default: firefox] 20:26:12 INFO - mkdir: /builds/slave/test/build/application 20:26:12 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'] 20:26:12 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 20:26:47 INFO - Reading from file tmpfile_stdout 20:26:47 INFO - Output received: 20:26:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:26:47 INFO - Running post-action listener: _resource_record_post_action 20:26:47 INFO - ##### 20:26:47 INFO - ##### Finished install step (success) 20:26:47 INFO - ##### 20:26:47 INFO - ##### 20:26:47 INFO - ##### Running run-tests step. 20:26:47 INFO - ##### 20:26:47 INFO - Running pre-action listener: _resource_record_pre_action 20:26:47 INFO - Running main action method: run_tests 20:26:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:26:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:26:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:26:47 INFO - 2016-05-18 20:26:47.195 screenresolution[1963:9024] starting screenresolution argv=screenresolution get 20:26:47 INFO - 2016-05-18 20:26:47.198 screenresolution[1963:9024] Display 0: 1600x1200x32@60 20:26:47 INFO - 2016-05-18 20:26:47.204 screenresolution[1964:9026] starting screenresolution argv=screenresolution list 20:26:47 INFO - Available Modes on Display 0 20:26:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:26:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:26:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:26:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:26:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:26:47 INFO - Intel Iris: 20:26:47 INFO - Chipset Model: Intel Iris 20:26:47 INFO - Type: GPU 20:26:47 INFO - Bus: Built-In 20:26:47 INFO - VRAM (Dynamic, Max): 1536 MB 20:26:47 INFO - Vendor: Intel (0x8086) 20:26:47 INFO - Device ID: 0x0a2e 20:26:47 INFO - Revision ID: 0x0009 20:26:47 INFO - Displays: 20:26:47 INFO - Display: 20:26:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:26:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:26:47 INFO - Display Serial Number: 1600x1200 60 20:26:47 INFO - Main Display: Yes 20:26:47 INFO - Mirror: Off 20:26:47 INFO - Online: Yes 20:26:47 INFO - Rotation: Supported 20:26:47 INFO - Return code: 0 20:26:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:26:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:26:47 INFO - Python 2.7.3 20:26:47 INFO - Return code: 0 20:26:47 INFO - grabbing minidump binary from tooltool 20:26: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')]} 20:26:47 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 0x10b73bd30>, '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 0x10b55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b732b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:26:47 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 20:26:47 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 20:26:47 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 20:26:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:26:47 INFO - Return code: 0 20:26:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:26:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:26:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:26:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:26:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:26:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:26:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:26:48 INFO - Return code: 0 20:26:48 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/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--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 20:26:48 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/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0132 --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 20:26:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xnLqKhodRx/Render', 20:26:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:26:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:26:48 INFO - 'HOME': '/Users/cltbld', 20:26:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:26:48 INFO - 'LOGNAME': 'cltbld', 20:26:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:26:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:26:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:26:48 INFO - 'MOZ_NO_REMOTE': '1', 20:26:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:26:48 INFO - 'NO_EM_RESTART': '1', 20:26:48 INFO - 'PAGER': '/bin/cat', 20:26:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:26:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:26:48 INFO - 'PWD': '/builds/slave/test', 20:26:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:26:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:26:48 INFO - 'SHELL': '/bin/bash', 20:26:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CB8CiSxLRw/Listeners', 20:26:48 INFO - 'TMPDIR': '/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/', 20:26:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:26:48 INFO - 'USER': 'cltbld', 20:26:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:26:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:26:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:26:48 INFO - 'XPC_FLAGS': '0x0', 20:26:48 INFO - 'XPC_SERVICE_NAME': '0', 20:26:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:26:48 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/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0132', '--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 20:26:48 INFO - mozversion application_buildid: 20160518193138 20:26:48 INFO - mozversion application_changeset: cee3b4a96f41e1bb792f267728f9f3083f08349a 20:26:48 INFO - mozversion application_display_name: Nightly 20:26:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:26:48 INFO - mozversion application_name: Firefox 20:26:48 INFO - mozversion application_remotingname: firefox 20:26:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 20:26:48 INFO - mozversion application_vendor: Mozilla 20:26:48 INFO - mozversion application_version: 49.0a1 20:26:48 INFO - mozversion platform_buildid: 20160518193138 20:26:48 INFO - mozversion platform_changeset: cee3b4a96f41e1bb792f267728f9f3083f08349a 20:26:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 20:26:48 INFO - mozversion platform_version: 49.0a1 20:26:48 INFO - using testdate: 1463628408 20:26:48 INFO - actual date: 1463628408 20:26:48 INFO - starting webserver on 'localhost:49382' 20:26:48 INFO - SUITE-START | Running 2 tests 20:26:48 INFO - TEST-START | dromaeo_css 20:26:48 INFO - operating with platform_type : mac_ 20:26:48 INFO - Initialising browser for dromaeo_css test... 20:26:48 INFO - TEST-INFO | started process 1982 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpoH1K4O/profile http://localhost:49382/getInfo.html) 20:26:51 INFO - PROCESS | 1982 | __metrics Screen width/height:1600/1200 20:26:51 INFO - PROCESS | 1982 | colorDepth:24 20:26:51 INFO - PROCESS | 1982 | Browser inner width/height: 1024/689 20:26:51 INFO - PROCESS | 1982 | __metrics 20:26:51 INFO - PROCESS | 1982 | 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" 20:26:51 ERROR - PROCESS | 1982 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:26:51 INFO - TEST-INFO | 1982: exit 0 20:26:51 INFO - Browser initialized. 20:26:51 INFO - Running cycle 1/1 for dromaeo_css test... 20:26:51 INFO - TEST-INFO | started process 1984 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmpoH1K4O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:27:03 INFO - PROCESS | 1984 | RSS: Main: 238952448 20:27:03 INFO - PROCESS | 1984 | 20:27:54 INFO - PROCESS | 1984 | 1463628474950 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] 20:30:06 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49382/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49382/tests/dromaeo/cssquery-ext.html) 20:30:07 INFO - PROCESS | 1984 | RSS: Main: 370487296 20:30:07 INFO - PROCESS | 1984 | 20:33:11 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49382/tests/dromaeo/cssquery-ext.html (next: http://localhost:49382/tests/dromaeo/cssquery-jquery.html) 20:33:11 INFO - PROCESS | 1984 | RSS: Main: 375148544 20:33:11 INFO - PROCESS | 1984 | 20:36:13 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49382/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49382/tests/dromaeo/cssquery-mootools.html) 20:36:14 INFO - PROCESS | 1984 | RSS: Main: 369848320 20:36:14 INFO - PROCESS | 1984 | 20:39:16 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49382/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49382/tests/dromaeo/cssquery-prototype.html) 20:39:17 INFO - PROCESS | 1984 | RSS: Main: 368349184 20:39:17 INFO - PROCESS | 1984 | 20:42:20 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49382/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49382/tests/dromaeo/cssquery-yui.html) 20:42:21 INFO - PROCESS | 1984 | RSS: Main: 337211392 20:42:21 INFO - PROCESS | 1984 | 20:45:24 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49382/tests/dromaeo/cssquery-yui.html (next: http://localhost:49382/tests/dromaeo/cssquery-dojo.html) 20:45:25 INFO - PROCESS | 1984 | RSS: Main: 375832576 20:45:25 INFO - PROCESS | 1984 | 20:45:25 INFO - PROCESS | 1984 | __start_tp_report 20:45:25 INFO - PROCESS | 1984 | _x_x_mozilla_page_load 20:45:25 INFO - PROCESS | 1984 | _x_x_mozilla_page_load_details 20:45:25 INFO - PROCESS | 1984 | |i|pagename|runs| 20:45:25 INFO - PROCESS | 1984 | |0;dojo.html;3475.1312171957006;3481.2341284917325;3498.282852015337;3511.4381698928178;3532.7527073104893;5768.884622307552;5788.913166302509;5821.592488525805;5872.853178670535;5929.9407005929925;4871.951280487203;5787.623804452707;5855.502282306009;5872.148538461921;5905.763769449228;5246.658967167141;5277.683338999653;5348.812791552296;5359.678419294835;5470.7264636768205;5443.428440013801;5449;5478.972605136989;5504.779808807663;5532.944670553304;4376;5288.2596436498925;5337.70642614656;5376.8118115865955;5398.514133727964;5361.303030605996;5364.409914909357;5368.1679339702405;5374.7850085996515;5386.380566234894;5276.551493123089;5309.867253318659;5312.096943519572;5327.653702509325;5361.597880159004;5743.798967036134;5777.6822274775295;5799.507041901444;5819.67991760453;5852.239208902815;6313.9368606314265;6356.2054743157105;6383.680815959184;6392.26488953767;6400.199975003125;6262.718177682015;6294.464970477515;6358.713857876361;6360.491160707179;6365.745370185187;5976.372480889531;6000.429959153874;6030.396960303979;6039.698015099271;6055;6387.936120638827;6475.4819614430735;6483.286838447767;6483.675816209171;6492.772752953624;6154;6215.285242197115;6265.780697675559;6279.026750853625;6351.491880649537;6366.299707032223;6371.331010243857;6374.872502550016;6399.976003839363;6400.167978162809;5194.558462530727;5343.492368225013;6352.841178970562;6364.331745166784;6371.649559274192;6246.937530624727;6292.968535157295;6359.904601430982;6379.553431259767;6391.744330226832;5040.899182016339;5079.034983353151;5084.51697088776;5089.033083714083;5112.284280200774;4501.68488205829;4549.886252843706;4570.063137056895;4601.171789077931;4607.723536587815;5751.281089863767;5768.855778605568;5812.738426770721;5817.883642327214;5831.970840145772;6298.874022519524;6328.367163283635;6343.746250149942;6379.617222966637;6380.84047898797;5680.40355762639;5739.454751798572;5818.7672493100645;5823.708814559255;5874.500667443315;5405.675659460445;5467.398586155519;5468.4531546845;5485.588580856452;5489.176623506426;6336;6412.198475190601;6413.615183089029;6440.2915679276175;6519.706613202512;6313.05304204373;6322.652254126067;6324.430801227912;6345.7144428499905;6346.524010699124;6417.93582064192;6494.902576461452;6524.3149469305035;6524.412802847766;6533.8693226136165;5097.413797413196;6145.477634401126;6238.282597501164;6294;6363.395477429637;5469.425710300361;6323.841903952438;6381.234251889803;6399.392057754506;6432.292447830829;6139.0321984548755;6287.245530536182;6289.40250676185;6298.716557755004;6302.023186406114;6188.845278868064;6227.96886015567;6237.96881015592;6316.305206427382;6323.841903952438;5260.658057226268;6163.876722465615;6167.321594624498;6168.9691551541955;6176.567640265318;6102.307478454246;6169.074638804215;6291.96854015727;6309.432151106423;6311.810645680637;5130;6101.762223876893;6166.475849552839;6171.56799024064;6179.3820617937845 20:45:25 INFO - PROCESS | 1984 | |1;ext.html;504.798579381073;505.2194359714241;511.0596502435518;529.5419462165228;582.5019608234958;30580;41506;42909;43019.13961720768;44879.55120448794;37126.628733712656;44002.55997440025;44543.77728111363;44615.77692111535;44867.55132448675;8484.448510846805;9528.190103841182;9776.169025632795;9779.364341317827;9790;8555.358348123884;11379.601713940014;11637.941810290937;11652.708682282926;11848;11565.959063684266;11650.883491165065;11667.6499705009;11688.29870207786;11760.647180584554;8491.532965686885;8689;11527.077833773319;11630.534778608846;11643.35961522116;11254.54981800727;11616.244944078693;11621.6513504595;11833.763324733543;11839.585614503538;40299.59700402988;40776.59223407758;40793.184136317104;40832.79583602093;40886.38670419946;3216.5014422764507;3282.097423208637;3359.81521016344;3387.119348969261;4087.9575708194516;28399.716002839912;28678.856605717046;28783.42433151325;29190.854045729637;29406.264843379086;2590.7279735627676;2597.4805038992276;2603.557395242813;2626.947461050768;2718.619393284922;468492;469414.65292673657;469732;469943;470465;29025.12924612265;60365;61391.693041534505;61454;61560.692196539625;30379.544306835414;36915.07712307214;36941;36977.44533831994;37049.814750926074;15161.317740701696;15364.923175384052;15379.231038448033;15388.999715018488;15398;10999.890001100046;11762.58830940913;11971.64085077449;12831.35843207836;12945.54690585825;8042.557659328793;8088.919110808934;8100.432969692183;8167.63245653947;8185.222403871623;2905.259158914463;3342.782719123249;3351.2292172800394;3372.3929692655065;3379.712724418403;920.7606022434215;953.4327075390132;1016.588281745894;1063.6596289187385;1162.4768854015726;28152.71847281542;29375.706242937726;29517.11448656544;29559.26101847428;30302.84848575743;1075.5858994287255;1131.9434028298551;1145.8109411946934;1164.1036401970434;1166.5742004168417;15163.393464261306;15171.544853654408;15354.232288385536;15570;15672.451464198699;732232;740119;742185.4453638594;742314;742586;61567;62019.68990155111;62056.68971655203;62058.37941620616;62186.689066554376;1306.3141850528548;1368.0697125954248;1429.907056041354;1465.6116129225763;1483.0730793254215;23277.650835237822;27422;27937.720622793513;28340;30935.69064309418;50429.99140017253;51084.48915510946;51105.97788044293;51275.48724512856;51278.46164614918;462532;557784;558041;605411;923825;34325.828370857984;39240.803795980835;40625.984350391474;41716.3742543856;41980.37029444622;40586.79706601448;46571.76714116408;46703.766481167375;48174.75912620555;48197.75901120472;36768.44847327239;38022;38233;38984.41523377208;39028.80485597554;31568.684313157493;32167.195820104684;36873;36898.815505923376;37336.87989360323;31488.842555787076;33627;38704;39049.60950390573;39164.6083539161;27026.1892143236;27557.31106722348;32423.513647295782;33081.50377744288;33820.661793381754;24776.380590485383;24892.37769055788;25059;25255.36861578475;25530.361740956625 20:45:25 INFO - PROCESS | 1984 | |2;jquery.html;387.49625486867075;392.90373858404683;393.13902553408053;395.94060890866365;410.67966985751127;13889.861101388982;20068;21282.57434851302;21287.25494607689;21331.720096794204;15822.892397532178;17970.37103701371;20386;20946.790532094674;21120.366389008355;6000.12998115272;6139.416755408229;6151.90772138416;6187.628742275478;6243.65659888706;6027.367126451725;6116.571839971204;6164;6165.414285642857;6167.845803854894;5912.940870591304;6053.061775424816;6083.209182806251;6087.512998960073;6089.238845144357;5910.911336329937;5922.052471604523;6055.515558755289;6056.757729690807;6067.939320606803;5293.947060529384;5314.840554783363;5319.6808191508635;5326.387465441447;5328.147496400557;25520.489590208093;25777.52003479525;26801;26879;27011.48850649403;1854.962900741991;2140.818030467412;2322.396176993994;2901.2166714987047;2972.6432828060556;14167.433302667881;15022.023568468014;15363.4622788203;15783.210839457981;16095;1536.8847336449699;1854.6754317994432;1887.4337698690476;1902.077492416177;1902.5243689077731;105659.47170264176;106739;116504.8349516511;119863;120597;9691.466969316614;11903.523859045541;11924.284542927453;11958.342291174069;12088.697782555506;15352.309146088453;15420.151891646066;15427.5371738848;15466.5360039199;15466.922665386826;7567.545947243183;7695.461317707706;8753.737387878375;8916.910830891738;8940.105989401138;8465.068842427328;8713.520756358334;8719.65121395137;8865.024847266795;8885.622728477096;3973.7019723520852;4735.644826638016;4743.644226683013;4745.288206768943;4795.856124316276;1935.7677078750357;1973.9802601973797;1978.8317992970758;1982.3755517012096;2010.5375763574168;876.4522173641474;947.5120313038791;948.1608776233084;1011.6610935336745;1039.7868436970402;15745.763813542806;16094.356225751073;20449.48876278105;20727.481812954495;21114.15543378248;898.5237823953315;927.1516562345379;937.4000639590668;992.1814503035025;1027.4759872465097;8208.794780130545;9584.396936821908;10967.451627418597;11322.660320190407;11495.770084598262;500866;529021;530485;642120;644934;144966.27516862558;151696.2415187917;155578;155604;158732;860.3676297921023;1008.1430783833682;1015.1016350529834;1016.8322226832638;1018.9388636681823;15369.615759605653;17695.646087078443;19280.517987049876;19302.4209273722;19339.033048347806;52105;57131.71434142803;57147.42852571588;57415.545523175235;57619.71190144023;81656.59171704104;84085.15914841018;85286.57356713177;86150.56924715337;86175.70736439084;7951.6482198025315;8197.877031844408;8326.50040997542;8338.624761885609;8408.705695300636;10385.480725963822;11341.09271258281;11697.941510292394;12380.876191237972;12796.552120675731;7788.070210481681;10573.2598718089;11065.280756750786;11091.94454027757;11288.379139147426;10669.626563070255;10793.568257269935;10916.126709863032;11096.833547496955;11212.607558735404;11183;11260.662180134608;11394.487248074023;11607.955284024478;11639.53441862316;8269.793255168668;8332.04181519133;8350.164983501843;8353.707620233263;8506.843240711569;9987.500624968578;10308.484575771032;10595.046445819913;11358.0913526916;11423 20:45:25 INFO - PROCESS | 1984 | |3;mootools.html;1448.847870973547;1611.1460925709373;1626.7478540826173;1878.2017642501942;1987.5428651410175;8137.349012079027;8950.55247237638;9174.724758257245;9176.22002129818;9295.30285228609;8850;9109.498977556232;9110.45337279762;9111.362204645677;9118.08819118088;2220;2294.678744975705;2467.814913881457;2500.899964001442;2539.00978618339;2538.200466852945;2582.909598164065;2584.547704151775;2590.883410246534;2594.0272397850804;2485.751424857518;2541.9110331138413;2543.6438898554215;2547.3758929062315;2549.9362515937155;2200.2409168836725;2423.8303318767694;2486.651868738369;2503.574392353304;2507.6489291499206;2524.0534799450206;2529.734377890313;2549.757773011561;2552.6043463263222;2559.9360015999564;9060.637574497012;9207.217386522154;9214.078592140731;9220.631174753002;9241.768955776091;5084.949150508484;5544.6118771686;5722.399148089432;5734.053881109612;5735.598508104435;3601.567811862567;3613.8012409317475;3615.9096022599383;3701.5002974598356;3709.5161935225856;957.25812495316;970.1026550440814;1024.329064462778;1029.12524354299;1044.7022598559374;12520;12526.060545459128;12527.373631318489;12529.18560293578;12551.372431378486;4978.477259887732;5008.348914641073;5115.5396014358885;5124.590032797368;5131.666441681279;10508.264421490423;10516.947415262875;10528.473576321307;10546.947265263625;10560.630377936735;5589.636673616284;5675.829725108253;5680.573956953245;5695.601307908407;5703.315602127771;4639.721616703008;4791;5037.622178336639;5162.8967420652125;5169.017886601609;4108.110269276107;4175.436316097288;4191.140816132685;4222.915541689211;4245.68157388191;1143.4568579924469;1364.8089267502558;1370.9314534273246;1374.1342953938954;1378.5795332423706;648.8053583924898;812.3095368936357;822.43663090783;832.5305835200875;906.7279816055157;5705.914411283834;5879.746488830398;5951.285845698544;5977.163197152402;6008.669523176267;961.1061712607342;963.3593660215878;983.041534503853;983.8671779309701;1002.0830939690247;3344.5652065231366;3768.3970564709516;3803.3154032274456;3948.861789837342;3949.8025098744947;11084.057855082408;11131.443427828575;11236.887631123747;11475.311481311148;11475.77048459026;10450.320729152581;11399.430028498542;11429.142814288962;11433.599824006285;11477.827832582518;8033.638486268132;8273.710420135267;8330.042045164762;8336.583170841555;8344.165583441607;5964.165016897725;6035.426634469718;6050.122732203894;6201.255849298113;6286.402791734778;10113;10410.739731506774;10542.367457952547;10550.8417373741;10588.894111058791;10710.839337410102;10726.570937162738;10731.678049658523;10732.839007415052;10779.137668986305;7677.808054798675;7725.690972361042;7731.381489480716;7887.092984306878;7892.684292628231;8447.40868139249;8504.957475212585;8557.657693692183;8573.914260857311;8601.956990215009;6015.887060893749;6934.757283495053;7388.815279618053;7440.069991251094;7553.546787192786;7450.776476705708;7606.239376062349;7671;7676.846463070819;7816.765497035098;7260.019897314006;7325.890111648436;7339.009233753375;7350.7427240046345;7352.595607241653;4893.779779909842;5088.363954505687;5144.948550514447;5158.329417175743;5164.638475306692;5031.84904452867;5131.9992601443855;5160.793568257228;5181.585473162213;5246.3704355477585 20:45:25 INFO - PROCESS | 1984 | |4;prototype.html;312.2303521818717;319.62124882014814;324.7596778383997;328.62372583392033;333.72967896004224;4531.977340113299;5416.5625468539165;5428.348598168215;5434.864128396792;5450.901402959859;4945.145570411096;5360.892782144365;5368.731563421833;5394.595405344595;5403.594730395226;3221.935561288767;3482.860685572574;3968.7420317679403;4092.2224777292367;4220.599043090901;4139.834406623731;4146.087860670647;4149.232392007473;4154.106867023589;4171.8539851105215;3496.737744669147;3886.261610294035;4109.773962432065;4111.129436106565;4136.958630413703;3449.4653328734084;3584.62361452049;4098.8360465581345;4113.321301985169;4125.432335712425;3282.868685252587;3286.277019055804;3533.6289689582736;3546.1666508370513;3573.624769399202;3738.7195960302815;4034.636882680573;5116;5134.8202812901745;5157.226416037587;4198.727082739612;4239.364095385686;4286.464191976003;4308.9138217235795;4440.622547083502;5461.344638643349;5547.44525547446;5558.777648894039;5575.163725441175;5596.27248457702;3821.5287114460853;3838.6929045676557;3854.2484215577974;3864.574896761354;3869.1487872667967;5131.589472842164;5532.585056120847;5698.886022279573;5836.328822185418;5872.559558033165;3894.805259737002;3923.862664806718;3930.194310166409;3931.980340098281;3938.8424463021156;3987.1427643056304;3997.360422332413;3997.9805149687227;4020.6381425671243;4026.275270451288;4632.351470794091;4639.350490931272;4642.396488456547;4649.000464899995;4670.225314380582;4622.560856718606;5183.533481986639;5611.5230205433;5631.662100273997;5667.149927510906;1150.551284998851;1331.4674130347744;1350.1628990026247;1353.2421843767522;1353.6345186799706;731.974380896666;814.8859159717645;834.61190546395;851.9478444121505;869.7953334631454;5150.072986862326;5220.616536617799;5250.632455728062;5253.658512196695;5262.60530460217;4858.80564777412;4881.95118048822;5100.38795344561;5744.874396473248;5792.478676919098;4042.4542686737504;5178.326817513699;5180.430152683201;5217.869553261199;5323.813666521653;2582.2253324002722;3006.083144640862;3049.4968330225265;3058.6788387219467;3071.6314042315066;5830.621009634445;5890.852728681817;5939.613925094855;6031.276246850406;6031.336552979169;5582.385937546866;5749.798757043483;5896.968030594715;5970.761169553256;5990.850228744316;5687.146927960839;6015.285643591643;6018.368071352533;6040.637561746222;6077.422644848821;4526.592606665416;4611.423572053493;5113.463086376026;5369.946300537101;5754.036671114296;4615.423072115986;5439.265699130567;5704.942950570441;5733;5785.4214578541805;5232.581393488588;5580.581456390706;5635.323761148525;5658.943410565842;5766.077427611562;2844.5448728203387;3000.5949196858146;3043.710847469487;3096.3342881280632;3730.6756101584633;4628.375169352094;4630.72215667061;5778.27771528559;5787.016207244695;5806.9419305806405;5580.414056524007;5588.776448941997;5619.342294023191;5624.915626265528;5655.632383895034;4138.5654506277615;4769.95230047709;4967.577755890645;5667.611435198402;5683.2895888014;5077;5269.973650131725;5477.370102438271;5511.173324001432;5520.613557050968;5239.580833533384;5511.063119269814;5532.391436941853;5659.264295641705;5661.688607126647;4478.888027799331;4640.744759038285;5326.147816349366;5504.174373843955;5543.61194716366 20:45:25 INFO - PROCESS | 1984 | |5;yui.html;646.8900286951218;650.9511786616002;661.5666738286421;674.578388507183;730.4667146366277;14706.705865882675;15278;15349.693006139898;15357.46248881288;15374.769378459332;13635.250061246628;14032.929835350835;14044.929775351135;14050.64873378166;14057.086289391182;889.5107690770083;897.6274845938927;907.3512438606396;907.4419232172199;908.0919080919081;716.6631683108931;735.7130719019586;886.1271647427279;904.9411788233776;910.9498977556232;963.3786207895903;971.9659811906585;972.0716715536646;973.5521660036392;973.9415635061919;959.5729900194416;961.0629636104812;961.2310151878469;961.7691753979065;963.1812958984842;551.9061759500854;689.7723751162105;709.2092317066433;709.4643544124204;713.5112447973155;14957.177355245458;14999.400023999027;15001.024933379296;15006.699866002728;15036.548903532912;12030.939845300805;12219.2668439893;12249.326287054208;12357;12539;9899.45552994585;10912;11126.554937802479;11691.123165762601;11761.823572646417;4153.91692166158;4181.790910454495;4299.6775241856985;4305.526392096866;4411.382406463098;87938.56030719806;89375.10624893667;89988;90502;90711.54644226802;9709.66016189444;9856.162226210852;9951.55218015191;10074.899251007542;10240.385576865412;18310.450686479428;18313.3590324338;22123.557528849335;22139.225127120473;22142.22502212415;6658.83352916181;7159.785206443711;7443.627818609048;7543.962280188564;7550.962245188739;3990.1620659661216;4074.144429669743;4080.0925443235014;4086.5096188457574;4130.297849365615;3438.5186073949667;3527.153483164022;3533.1343820763564;3533.4169861973;3536.7701099428455;624.3537938233997;634.0299342006737;639.9136116624289;646.1599920103846;661.1141070964834;489.52516059422305;513.1712284660247;546.4889580310445;580.8925348810399;612.3784358875745;7927.16764739694;7968.920310796933;8070.950776399148;8307.08622051574;8426;551.2778260478785;580.9099589563624;592.950477654549;605.5367643752533;607.781198768443;3216.9223310190882;3814.8855534334016;3858.884233473;3979.2041591681777;3991.640752332304;86892;92890.53554732318;94104;94302.52848735849;96968.51515742377;80380.59809700915;80446.5977670108;82565.17434825575;83541;83762.3247535046;72379.63810180915;75393;75670.6216468914;75892.86160707593;76040.61979690066;7842.960785196037;8158.757446128099;8276.680157100478;8757.47455152693;8947.463152210888;45059.09881802411;45310.77344613256;45721.54278457306;46079.76960115178;46307.53692463032;44137.11725765403;49431.75284123556;62585;63295.05057424234;66467;10096.596136154765;11613.883861161281;11797.233179843282;11891.345975971404;11944.20836874441;11874.321132823678;12003.459844307201;12046.277223366626;12164.513419463123;12571.497140114294;11514.251573648022;11617.419129043345;11890.702732431413;11891.464884080064;11895.405229738652;11246.550137994716;11456.656300311004;11459.541618335174;11473.196876218584;11655.475503602527;11251.043661288555;11342.829857552308;11648.475818588015;11905.761884762083;12571;9369.297302702462;9574.425534467955;9763.41419514803;9912.603495860085;10140.645077422254;6782.626955517494;9492.72122025135;9785.36395134314;9798.559064841957;9860.06329398706 20:45:25 INFO - PROCESS | 1984 | __end_tp_report 20:45:25 INFO - PROCESS | 1984 | __start_cc_report 20:45:25 INFO - PROCESS | 1984 | _x_x_mozilla_cycle_collect,1392 20:45:25 INFO - PROCESS | 1984 | __end_cc_report 20:45:25 INFO - PROCESS | 1984 | __startTimestamp1463629525061__endTimestamp 20:45:25 INFO - PROCESS | 1984 | 20:45:25 INFO - PROCESS | 1984 | ------- Summary: start ------- 20:45:25 INFO - PROCESS | 1984 | Number of tests: 6 20:45:25 INFO - PROCESS | 1984 | 20:45:25 INFO - PROCESS | 1984 | [#0] dojo.html Cycles:165 Average:5841.15 Median:6102.31 stddev:656.80 (10.8%) stddev-sans-first:632.04 20:45:25 INFO - PROCESS | 1984 | Values: 3475.1 3481.2 3498.3 3511.4 3532.8 5768.9 5788.9 5821.6 5872.9 5929.9 4872.0 5787.6 5855.5 5872.1 5905.8 5246.7 5277.7 5348.8 5359.7 5470.7 5443.4 5449.0 5479.0 5504.8 5532.9 4376.0 5288.3 5337.7 5376.8 5398.5 5361.3 5364.4 5368.2 5374.8 5386.4 5276.6 5309.9 5312.1 5327.7 5361.6 5743.8 5777.7 5799.5 5819.7 5852.2 6313.9 6356.2 6383.7 6392.3 6400.2 6262.7 6294.5 6358.7 6360.5 6365.7 5976.4 6000.4 6030.4 6039.7 6055.0 6387.9 6475.5 6483.3 6483.7 6492.8 6154.0 6215.3 6265.8 6279.0 6351.5 6366.3 6371.3 6374.9 6400.0 6400.2 5194.6 5343.5 6352.8 6364.3 6371.6 6246.9 6293.0 6359.9 6379.6 6391.7 5040.9 5079.0 5084.5 5089.0 5112.3 4501.7 4549.9 4570.1 4601.2 4607.7 5751.3 5768.9 5812.7 5817.9 5832.0 6298.9 6328.4 6343.7 6379.6 6380.8 5680.4 5739.5 5818.8 5823.7 5874.5 5405.7 5467.4 5468.5 5485.6 5489.2 6336.0 6412.2 6413.6 6440.3 6519.7 6313.1 6322.7 6324.4 6345.7 6346.5 6417.9 6494.9 6524.3 6524.4 6533.9 5097.4 6145.5 6238.3 6294.0 6363.4 5469.4 6323.8 6381.2 6399.4 6432.3 6139.0 6287.2 6289.4 6298.7 6302.0 6188.8 6228.0 6238.0 6316.3 6323.8 5260.7 6163.9 6167.3 6169.0 6176.6 6102.3 6169.1 6292.0 6309.4 6311.8 5130.0 6101.8 6166.5 6171.6 6179.4 20:45:25 INFO - PROCESS | 1984 | 20:45:25 INFO - PROCESS | 1984 | [#1] ext.html Cycles:180 Average:72619.56 Median:18856.47 stddev:168678.25 (894.5%) stddev-sans-first:169064.54 20:45:25 INFO - PROCESS | 1984 | Values: 504.8 505.2 511.1 529.5 582.5 30580.0 41506.0 42909.0 43019.1 44879.6 37126.6 44002.6 44543.8 44615.8 44867.6 8484.4 9528.2 9776.2 9779.4 9790.0 8555.4 11379.6 11637.9 11652.7 11848.0 11566.0 11650.9 11667.6 11688.3 11760.6 8491.5 8689.0 11527.1 11630.5 11643.4 11254.5 11616.2 11621.7 11833.8 11839.6 40299.6 40776.6 40793.2 40832.8 40886.4 3216.5 3282.1 3359.8 3387.1 4088.0 28399.7 28678.9 28783.4 29190.9 29406.3 2590.7 2597.5 2603.6 2626.9 2718.6 468492.0 469414.7 469732.0 469943.0 470465.0 29025.1 60365.0 61391.7 61454.0 61560.7 30379.5 36915.1 36941.0 36977.4 37049.8 15161.3 15364.9 15379.2 15389.0 15398.0 10999.9 11762.6 11971.6 12831.4 12945.5 8042.6 8088.9 8100.4 8167.6 8185.2 2905.3 3342.8 3351.2 3372.4 3379.7 920.8 953.4 1016.6 1063.7 1162.5 28152.7 29375.7 29517.1 29559.3 30302.8 1075.6 1131.9 1145.8 1164.1 1166.6 15163.4 15171.5 15354.2 15570.0 15672.5 732232.0 740119.0 742185.4 742314.0 742586.0 61567.0 62019.7 62056.7 62058.4 62186.7 1306.3 1368.1 1429.9 1465.6 1483.1 23277.7 27422.0 27937.7 28340.0 30935.7 50430.0 51084.5 51106.0 51275.5 51278.5 462532.0 557784.0 558041.0 605411.0 923825.0 34325.8 39240.8 40626.0 41716.4 41980.4 40586.8 46571.8 46703.8 48174.8 48197.8 36768.4 38022.0 38233.0 38984.4 39028.8 31568.7 32167.2 36873.0 36898.8 37336.9 31488.8 33627.0 38704.0 39049.6 39164.6 27026.2 27557.3 32423.5 33081.5 33820.7 24776.4 24892.4 25059.0 25255.4 25530.4 20:45:25 INFO - PROCESS | 1984 | 20:45:25 INFO - PROCESS | 1984 | [#2] jquery.html Cycles:180 Average:35140.67 Median:25649.00 stddev:96660.36 (376.9%) stddev-sans-first:96896.29 20:45:25 INFO - PROCESS | 1984 | Values: 387.5 392.9 393.1 395.9 410.7 13889.9 20068.0 21282.6 21287.3 21331.7 15822.9 17970.4 20386.0 20946.8 21120.4 6000.1 6139.4 6151.9 6187.6 6243.7 6027.4 6116.6 6164.0 6165.4 6167.8 5912.9 6053.1 6083.2 6087.5 6089.2 5910.9 5922.1 6055.5 6056.8 6067.9 5293.9 5314.8 5319.7 5326.4 5328.1 25520.5 25777.5 26801.0 26879.0 27011.5 1855.0 2140.8 2322.4 2901.2 2972.6 14167.4 15022.0 15363.5 15783.2 16095.0 1536.9 1854.7 1887.4 1902.1 1902.5 105659.5 106739.0 116504.8 119863.0 120597.0 9691.5 11903.5 11924.3 11958.3 12088.7 15352.3 15420.2 15427.5 15466.5 15466.9 7567.5 7695.5 8753.7 8916.9 8940.1 8465.1 8713.5 8719.7 8865.0 8885.6 3973.7 4735.6 4743.6 4745.3 4795.9 1935.8 1974.0 1978.8 1982.4 2010.5 876.5 947.5 948.2 1011.7 1039.8 15745.8 16094.4 20449.5 20727.5 21114.2 898.5 927.2 937.4 992.2 1027.5 8208.8 9584.4 10967.5 11322.7 11495.8 500866.0 529021.0 530485.0 642120.0 644934.0 144966.3 151696.2 155578.0 155604.0 158732.0 860.4 1008.1 1015.1 1016.8 1018.9 15369.6 17695.6 19280.5 19302.4 19339.0 52105.0 57131.7 57147.4 57415.5 57619.7 81656.6 84085.2 85286.6 86150.6 86175.7 7951.6 8197.9 8326.5 8338.6 8408.7 10385.5 11341.1 11697.9 12380.9 12796.6 7788.1 10573.3 11065.3 11091.9 11288.4 10669.6 10793.6 10916.1 11096.8 11212.6 11183.0 11260.7 11394.5 11608.0 11639.5 8269.8 8332.0 8350.2 8353.7 8506.8 9987.5 10308.5 10595.0 11358.1 11423.0 20:45:25 INFO - PROCESS | 1984 | 20:45:25 INFO - PROCESS | 1984 | [#3] mootools.html Cycles:180 Average:5944.50 Median:5061.29 stddev:3370.04 (66.6%) stddev-sans-first:3362.56 20:45:25 INFO - PROCESS | 1984 | Values: 1448.8 1611.1 1626.7 1878.2 1987.5 8137.3 8950.6 9174.7 9176.2 9295.3 8850.0 9109.5 9110.5 9111.4 9118.1 2220.0 2294.7 2467.8 2500.9 2539.0 2538.2 2582.9 2584.5 2590.9 2594.0 2485.8 2541.9 2543.6 2547.4 2549.9 2200.2 2423.8 2486.7 2503.6 2507.6 2524.1 2529.7 2549.8 2552.6 2559.9 9060.6 9207.2 9214.1 9220.6 9241.8 5084.9 5544.6 5722.4 5734.1 5735.6 3601.6 3613.8 3615.9 3701.5 3709.5 957.3 970.1 1024.3 1029.1 1044.7 12520.0 12526.1 12527.4 12529.2 12551.4 4978.5 5008.3 5115.5 5124.6 5131.7 10508.3 10516.9 10528.5 10546.9 10560.6 5589.6 5675.8 5680.6 5695.6 5703.3 4639.7 4791.0 5037.6 5162.9 5169.0 4108.1 4175.4 4191.1 4222.9 4245.7 1143.5 1364.8 1370.9 1374.1 1378.6 648.8 812.3 822.4 832.5 906.7 5705.9 5879.7 5951.3 5977.2 6008.7 961.1 963.4 983.0 983.9 1002.1 3344.6 3768.4 3803.3 3948.9 3949.8 11084.1 11131.4 11236.9 11475.3 11475.8 10450.3 11399.4 11429.1 11433.6 11477.8 8033.6 8273.7 8330.0 8336.6 8344.2 5964.2 6035.4 6050.1 6201.3 6286.4 10113.0 10410.7 10542.4 10550.8 10588.9 10710.8 10726.6 10731.7 10732.8 10779.1 7677.8 7725.7 7731.4 7887.1 7892.7 8447.4 8505.0 8557.7 8573.9 8602.0 6015.9 6934.8 7388.8 7440.1 7553.5 7450.8 7606.2 7671.0 7676.8 7816.8 7260.0 7325.9 7339.0 7350.7 7352.6 4893.8 5088.4 5144.9 5158.3 5164.6 5031.8 5132.0 5160.8 5181.6 5246.4 20:45:25 INFO - PROCESS | 1984 | 20:45:25 INFO - PROCESS | 1984 | [#4] prototype.html Cycles:180 Average:4486.10 Median:5114.73 stddev:1401.98 (27.4%) stddev-sans-first:1370.46 20:45:25 INFO - PROCESS | 1984 | Values: 312.2 319.6 324.8 328.6 333.7 4532.0 5416.6 5428.3 5434.9 5450.9 4945.1 5360.9 5368.7 5394.6 5403.6 3221.9 3482.9 3968.7 4092.2 4220.6 4139.8 4146.1 4149.2 4154.1 4171.9 3496.7 3886.3 4109.8 4111.1 4137.0 3449.5 3584.6 4098.8 4113.3 4125.4 3282.9 3286.3 3533.6 3546.2 3573.6 3738.7 4034.6 5116.0 5134.8 5157.2 4198.7 4239.4 4286.5 4308.9 4440.6 5461.3 5547.4 5558.8 5575.2 5596.3 3821.5 3838.7 3854.2 3864.6 3869.1 5131.6 5532.6 5698.9 5836.3 5872.6 3894.8 3923.9 3930.2 3932.0 3938.8 3987.1 3997.4 3998.0 4020.6 4026.3 4632.4 4639.4 4642.4 4649.0 4670.2 4622.6 5183.5 5611.5 5631.7 5667.1 1150.6 1331.5 1350.2 1353.2 1353.6 732.0 814.9 834.6 851.9 869.8 5150.1 5220.6 5250.6 5253.7 5262.6 4858.8 4882.0 5100.4 5744.9 5792.5 4042.5 5178.3 5180.4 5217.9 5323.8 2582.2 3006.1 3049.5 3058.7 3071.6 5830.6 5890.9 5939.6 6031.3 6031.3 5582.4 5749.8 5897.0 5970.8 5990.9 5687.1 6015.3 6018.4 6040.6 6077.4 4526.6 4611.4 5113.5 5369.9 5754.0 4615.4 5439.3 5704.9 5733.0 5785.4 5232.6 5580.6 5635.3 5658.9 5766.1 2844.5 3000.6 3043.7 3096.3 3730.7 4628.4 4630.7 5778.3 5787.0 5806.9 5580.4 5588.8 5619.3 5624.9 5655.6 4138.6 4770.0 4967.6 5667.6 5683.3 5077.0 5270.0 5477.4 5511.2 5520.6 5239.6 5511.1 5532.4 5659.3 5661.7 4478.9 4640.7 5326.1 5504.2 5543.6 20:45:25 INFO - PROCESS | 1984 | 20:45:25 INFO - PROCESS | 1984 | [#5] yui.html Cycles:180 Average:18461.75 Median:586.93 stddev:26342.52 (4488.2%) stddev-sans-first:26382.45 20:45:25 INFO - PROCESS | 1984 | Values: 646.9 651.0 661.6 674.6 730.5 14706.7 15278.0 15349.7 15357.5 15374.8 13635.3 14032.9 14044.9 14050.6 14057.1 889.5 897.6 907.4 907.4 908.1 716.7 735.7 886.1 904.9 910.9 963.4 972.0 972.1 973.6 973.9 959.6 961.1 961.2 961.8 963.2 551.9 689.8 709.2 709.5 713.5 14957.2 14999.4 15001.0 15006.7 15036.5 12030.9 12219.3 12249.3 12357.0 12539.0 9899.5 10912.0 11126.6 11691.1 11761.8 4153.9 4181.8 4299.7 4305.5 4411.4 87938.6 89375.1 89988.0 90502.0 90711.5 9709.7 9856.2 9951.6 10074.9 10240.4 18310.5 18313.4 22123.6 22139.2 22142.2 6658.8 7159.8 7443.6 7544.0 7551.0 3990.2 4074.1 4080.1 4086.5 4130.3 3438.5 3527.2 3533.1 3533.4 3536.8 624.4 634.0 639.9 646.2 661.1 489.5 513.2 546.5 580.9 612.4 7927.2 7968.9 8071.0 8307.1 8426.0 551.3 580.9 593.0 605.5 607.8 3216.9 3814.9 3858.9 3979.2 3991.6 86892.0 92890.5 94104.0 94302.5 96968.5 80380.6 80446.6 82565.2 83541.0 83762.3 72379.6 75393.0 75670.6 75892.9 76040.6 7843.0 8158.8 8276.7 8757.5 8947.5 45059.1 45310.8 45721.5 46079.8 46307.5 44137.1 49431.8 62585.0 63295.1 66467.0 10096.6 11613.9 11797.2 11891.3 11944.2 11874.3 12003.5 12046.3 12164.5 12571.5 11514.3 11617.4 11890.7 11891.5 11895.4 11246.6 11456.7 11459.5 11473.2 11655.5 11251.0 11342.8 11648.5 11905.8 12571.0 9369.3 9574.4 9763.4 9912.6 10140.6 6782.6 9492.7 9785.4 9798.6 9860.1 20:45:25 INFO - PROCESS | 1984 | -------- Summary: end -------- 20:45:25 INFO - PROCESS | 1984 | 20:45:25 INFO - TEST-INFO | 1984: exit 0 20:45:25 INFO - TEST-OK | dromaeo_css | took 1116697ms 20:45:25 INFO - TEST-START | kraken 20:45:25 INFO - operating with platform_type : mac_ 20:45:25 INFO - Initialising browser for kraken test... 20:45:25 INFO - TEST-INFO | started process 1990 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmp4dXLgf/profile http://localhost:49382/getInfo.html) 20:45:28 INFO - PROCESS | 1990 | __metrics Screen width/height:1600/1200 20:45:28 INFO - PROCESS | 1990 | colorDepth:24 20:45:28 INFO - PROCESS | 1990 | Browser inner width/height: 1024/689 20:45:28 INFO - PROCESS | 1990 | __metrics 20:45:28 INFO - PROCESS | 1990 | 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" 20:45:28 ERROR - PROCESS | 1990 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:45:28 INFO - TEST-INFO | 1990: exit 0 20:45:28 INFO - Browser initialized. 20:45:28 INFO - Running cycle 1/1 for kraken test... 20:45:28 INFO - TEST-INFO | started process 1992 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/T/tmp4dXLgf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:45:40 INFO - PROCESS | 1992 | RSS: Main: 238903296 20:45:40 INFO - PROCESS | 1992 | 20:45:43 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49382/tests/kraken/driver.html?testName=audio-beat-detection) 20:45:44 INFO - PROCESS | 1992 | RSS: Main: 287617024 20:45:44 INFO - PROCESS | 1992 | 20:45:47 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49382/tests/kraken/driver.html?testName=audio-dft) 20:45:47 INFO - PROCESS | 1992 | RSS: Main: 297533440 20:45:47 INFO - PROCESS | 1992 | 20:45:51 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49382/tests/kraken/driver.html?testName=audio-fft) 20:45:52 INFO - PROCESS | 1992 | RSS: Main: 386215936 20:45:52 INFO - PROCESS | 1992 | 20:45:54 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49382/tests/kraken/driver.html?testName=audio-oscillator) 20:45:54 INFO - PROCESS | 1992 | RSS: Main: 285130752 20:45:54 INFO - PROCESS | 1992 | 20:45:57 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49382/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:45:57 INFO - PROCESS | 1992 | RSS: Main: 289087488 20:45:57 INFO - PROCESS | 1992 | 20:46:00 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49382/tests/kraken/driver.html?testName=imaging-darkroom) 20:46:01 INFO - PROCESS | 1992 | RSS: Main: 326303744 20:46:01 INFO - PROCESS | 1992 | 20:46:04 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49382/tests/kraken/driver.html?testName=imaging-desaturate) 20:46:05 INFO - PROCESS | 1992 | RSS: Main: 328122368 20:46:05 INFO - PROCESS | 1992 | 20:46:08 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49382/tests/kraken/driver.html?testName=json-parse-financial) 20:46:09 INFO - PROCESS | 1992 | RSS: Main: 326307840 20:46:09 INFO - PROCESS | 1992 | 20:46:10 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49382/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:46:11 INFO - PROCESS | 1992 | RSS: Main: 316289024 20:46:11 INFO - PROCESS | 1992 | 20:46:13 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:46:13 INFO - PROCESS | 1992 | RSS: Main: 322977792 20:46:13 INFO - PROCESS | 1992 | 20:46:16 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:46:16 INFO - PROCESS | 1992 | RSS: Main: 325754880 20:46:16 INFO - PROCESS | 1992 | 20:46:19 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:46:20 INFO - PROCESS | 1992 | RSS: Main: 330608640 20:46:20 INFO - PROCESS | 1992 | 20:46:23 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:46:24 INFO - PROCESS | 1992 | RSS: Main: 328224768 20:46:24 INFO - PROCESS | 1992 | 20:46:25 INFO - PROCESS | 1992 | Cycle 1(1): loaded http://localhost:49382/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49382/tests/kraken/driver.html?testName=ai-astar) 20:46:26 INFO - PROCESS | 1992 | RSS: Main: 375783424 20:46:26 INFO - PROCESS | 1992 | 20:46:26 INFO - PROCESS | 1992 | __start_tp_report 20:46:26 INFO - PROCESS | 1992 | _x_x_mozilla_page_load 20:46:26 INFO - PROCESS | 1992 | _x_x_mozilla_page_load_details 20:46:26 INFO - PROCESS | 1992 | |i|pagename|runs| 20:46:26 INFO - PROCESS | 1992 | |0;ai-astar;113;109;112;112;112;112;113;112;113;111 20:46:26 INFO - PROCESS | 1992 | |1;audio-beat-detection;160;125;134;229;140;162;140;117;166;117 20:46:26 INFO - PROCESS | 1992 | |2;audio-dft;186;187;185;185;185;185;186;184;183;187 20:46:26 INFO - PROCESS | 1992 | |3;audio-fft;79;73;57;123;57;81;82;82;114;56 20:46:26 INFO - PROCESS | 1992 | |4;audio-oscillator;92;101;147;113;111;77;104;79;79;102 20:46:26 INFO - PROCESS | 1992 | |5;imaging-gaussian-blur;91;88;92;88;91;88;92;88;92;88 20:46:26 INFO - PROCESS | 1992 | |6;imaging-darkroom;96;122;88;142;145;96;97;97;96;96 20:46:26 INFO - PROCESS | 1992 | |7;imaging-desaturate;84;85;84;84;83;83;85;83;84;83 20:46:26 INFO - PROCESS | 1992 | |8;json-parse-financial;57;57;58;58;58;55;56;117;55;54 20:46:26 INFO - PROCESS | 1992 | |9;json-stringify-tinderbox;63;68;67;73;70;67;65;68;67;68 20:46:26 INFO - PROCESS | 1992 | |10;stanford-crypto-aes;78;83;85;85;85;85;85;86;86;85 20:46:26 INFO - PROCESS | 1992 | |11;stanford-crypto-ccm;143;124;145;128;137;146;146;148;211;139 20:46:26 INFO - PROCESS | 1992 | |12;stanford-crypto-pbkdf2;162;168;163;165;162;167;163;160;165;163 20:46:26 INFO - PROCESS | 1992 | |13;stanford-crypto-sha256-iterative;65;67;66;66;67;74;82;84;70;66 20:46:26 INFO - PROCESS | 1992 | __end_tp_report 20:46:26 INFO - PROCESS | 1992 | __start_cc_report 20:46:26 INFO - PROCESS | 1992 | _x_x_mozilla_cycle_collect,2297 20:46:26 INFO - PROCESS | 1992 | __end_cc_report 20:46:26 INFO - PROCESS | 1992 | __startTimestamp1463629586181__endTimestamp 20:46:26 INFO - PROCESS | 1992 | 20:46:26 INFO - PROCESS | 1992 | ------- Summary: start ------- 20:46:26 INFO - PROCESS | 1992 | Number of tests: 14 20:46:26 INFO - PROCESS | 1992 | 20:46:26 INFO - PROCESS | 1992 | [#0] ai-astar Cycles:10 Average:111.90 Median:112.00 stddev:1.20 (1.1%) stddev-sans-first:1.20 20:46:26 INFO - PROCESS | 1992 | Values: 113.0 109.0 112.0 112.0 112.0 112.0 113.0 112.0 113.0 111.0 20:46:26 INFO - PROCESS | 1992 | 20:46:26 INFO - PROCESS | 1992 | [#1] audio-beat-detection Cycles:10 Average:149.00 Median:150.00 stddev:33.32 (22.2%) stddev-sans-first:35.10 20:46:26 INFO - PROCESS | 1992 | Values: 160.0 125.0 134.0 229.0 140.0 162.0 140.0 117.0 166.0 117.0 20:46:26 INFO - PROCESS | 1992 | 20:46:26 INFO - PROCESS | 1992 | [#2] audio-dft Cycles:10 Average:185.30 Median:185.50 stddev:1.25 (0.7%) stddev-sans-first:1.30 20:46:26 INFO - PROCESS | 1992 | Values: 186.0 187.0 185.0 185.0 185.0 185.0 186.0 184.0 183.0 187.0 20:46:26 INFO - PROCESS | 1992 | 20:46:26 INFO - PROCESS | 1992 | [#3] audio-fft Cycles:10 Average:80.40 Median:76.00 stddev:22.84 (30.1%) stddev-sans-first:24.22 20:46:26 INFO - PROCESS | 1992 | Values: 79.0 73.0 57.0 123.0 57.0 81.0 82.0 82.0 114.0 56.0 20:46:26 INFO - PROCESS | 1992 | 20:46:26 INFO - PROCESS | 1992 | [#4] audio-oscillator Cycles:10 Average:100.50 Median:112.00 stddev:21.06 (18.8%) stddev-sans-first:22.11 20:46:26 INFO - PROCESS | 1992 | Values: 92.0 101.0 147.0 113.0 111.0 77.0 104.0 79.0 79.0 102.0 20:46:26 INFO - PROCESS | 1992 | 20:46:26 INFO - PROCESS | 1992 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:91.00 stddev:1.93 (2.1%) stddev-sans-first:2.00 20:46:26 INFO - PROCESS | 1992 | Values: 91.0 88.0 92.0 88.0 91.0 88.0 92.0 88.0 92.0 88.0 20:46:26 INFO - PROCESS | 1992 | 20:46:26 INFO - PROCESS | 1992 | [#6] imaging-darkroom Cycles:10 Average:107.50 Median:96.00 stddev:20.91 (21.8%) stddev-sans-first:21.76 20:46:26 INFO - PROCESS | 1992 | Values: 96.0 122.0 88.0 142.0 145.0 96.0 97.0 97.0 96.0 96.0 20:46:26 INFO - PROCESS | 1992 | 20:46:26 INFO - PROCESS | 1992 | [#7] imaging-desaturate Cycles:10 Average:83.80 Median:84.00 stddev:0.79 (0.9%) stddev-sans-first:0.83 20:46:26 INFO - PROCESS | 1992 | Values: 84.0 85.0 84.0 84.0 83.0 83.0 85.0 83.0 84.0 83.0 20:46:26 INFO - PROCESS | 1992 | 20:46:26 INFO - PROCESS | 1992 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:57.00 stddev:19.20 (33.7%) stddev-sans-first:20.26 20:46:26 INFO - PROCESS | 1992 | Values: 57.0 57.0 58.0 58.0 58.0 55.0 56.0 117.0 55.0 54.0 20:46:26 INFO - PROCESS | 1992 | 20:46:26 INFO - PROCESS | 1992 | [#9] json-stringify-tinderbox Cycles:10 Average:67.60 Median:68.00 stddev:2.67 (3.9%) stddev-sans-first:2.26 20:46:26 INFO - PROCESS | 1992 | Values: 63.0 68.0 67.0 73.0 70.0 67.0 65.0 68.0 67.0 68.0 20:46:26 INFO - PROCESS | 1992 | 20:46:26 INFO - PROCESS | 1992 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:85.00 stddev:2.36 (2.8%) stddev-sans-first:0.87 20:46:26 INFO - PROCESS | 1992 | Values: 78.0 83.0 85.0 85.0 85.0 85.0 85.0 86.0 86.0 85.0 20:46:26 INFO - PROCESS | 1992 | 20:46:26 INFO - PROCESS | 1992 | [#11] stanford-crypto-ccm Cycles:10 Average:146.70 Median:145.50 stddev:23.97 (16.5%) stddev-sans-first:25.39 20:46:26 INFO - PROCESS | 1992 | Values: 143.0 124.0 145.0 128.0 137.0 146.0 146.0 148.0 211.0 139.0 20:46:26 INFO - PROCESS | 1992 | 20:46:26 INFO - PROCESS | 1992 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.80 Median:164.00 stddev:2.44 (1.5%) stddev-sans-first:2.50 20:46:26 INFO - PROCESS | 1992 | Values: 162.0 168.0 163.0 165.0 162.0 167.0 163.0 160.0 165.0 163.0 20:46:26 INFO - PROCESS | 1992 | 20:46:26 INFO - PROCESS | 1992 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.70 Median:68.50 stddev:7.01 (10.2%) stddev-sans-first:7.12 20:46:26 INFO - PROCESS | 1992 | Values: 65.0 67.0 66.0 66.0 67.0 74.0 82.0 84.0 70.0 66.0 20:46:26 INFO - PROCESS | 1992 | -------- Summary: end -------- 20:46:26 INFO - PROCESS | 1992 | 20:46:27 INFO - TEST-INFO | 1992: exit 0 20:46:27 INFO - TEST-OK | kraken | took 61505ms 20:46:27 INFO - SUITE-END | took 1178s 20:46:27 INFO - Completed test suite (00:19:38) 20:46:27 INFO - javascript benchmark 20:46:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3475.1312171957006, 3481.2341284917325, 3498.282852015337, 3511.4381698928178, 3532.7527073104893, 5768.884622307552, 5788.913166302509, 5821.592488525805, 5872.853178670535, 5929.9407005929925, 4871.951280487203, 5787.623804452707, 5855.502282306009, 5872.148538461921, 5905.763769449228, 5246.658967167141, 5277.683338999653, 5348.812791552296, 5359.678419294835, 5470.7264636768205, 5443.428440013801, 5449.0, 5478.972605136989, 5504.779808807663, 5532.944670553304, 4376.0, 5288.2596436498925, 5337.70642614656, 5376.8118115865955, 5398.514133727964, 5361.303030605996, 5364.409914909357, 5368.1679339702405, 5374.7850085996515, 5386.380566234894, 5276.551493123089, 5309.867253318659, 5312.096943519572, 5327.653702509325, 5361.597880159004, 5743.798967036134, 5777.6822274775295, 5799.507041901444, 5819.67991760453, 5852.239208902815, 6313.9368606314265, 6356.2054743157105, 6383.680815959184, 6392.26488953767, 6400.199975003125, 6262.718177682015, 6294.464970477515, 6358.713857876361, 6360.491160707179, 6365.745370185187, 5976.372480889531, 6000.429959153874, 6030.396960303979, 6039.698015099271, 6055.0, 6387.936120638827, 6475.4819614430735, 6483.286838447767, 6483.675816209171, 6492.772752953624, 6154.0, 6215.285242197115, 6265.780697675559, 6279.026750853625, 6351.491880649537, 6366.299707032223, 6371.331010243857, 6374.872502550016, 6399.976003839363, 6400.167978162809, 5194.558462530727, 5343.492368225013, 6352.841178970562, 6364.331745166784, 6371.649559274192, 6246.937530624727, 6292.968535157295, 6359.904601430982, 6379.553431259767, 6391.744330226832, 5040.899182016339, 5079.034983353151, 5084.51697088776, 5089.033083714083, 5112.284280200774, 4501.68488205829, 4549.886252843706, 4570.063137056895, 4601.171789077931, 4607.723536587815, 5751.281089863767, 5768.855778605568, 5812.738426770721, 5817.883642327214, 5831.970840145772, 6298.874022519524, 6328.367163283635, 6343.746250149942, 6379.617222966637, 6380.84047898797, 5680.40355762639, 5739.454751798572, 5818.7672493100645, 5823.708814559255, 5874.500667443315, 5405.675659460445, 5467.398586155519, 5468.4531546845, 5485.588580856452, 5489.176623506426, 6336.0, 6412.198475190601, 6413.615183089029, 6440.2915679276175, 6519.706613202512, 6313.05304204373, 6322.652254126067, 6324.430801227912, 6345.7144428499905, 6346.524010699124, 6417.93582064192, 6494.902576461452, 6524.3149469305035, 6524.412802847766, 6533.8693226136165, 5097.413797413196, 6145.477634401126, 6238.282597501164, 6294.0, 6363.395477429637, 5469.425710300361, 6323.841903952438, 6381.234251889803, 6399.392057754506, 6432.292447830829, 6139.0321984548755, 6287.245530536182, 6289.40250676185, 6298.716557755004, 6302.023186406114, 6188.845278868064, 6227.96886015567, 6237.96881015592, 6316.305206427382, 6323.841903952438, 5260.658057226268, 6163.876722465615, 6167.321594624498, 6168.9691551541955, 6176.567640265318, 6102.307478454246, 6169.074638804215, 6291.96854015727, 6309.432151106423, 6311.810645680637, 5130.0, 6101.762223876893, 6166.475849552839, 6171.56799024064, 6179.3820617937845], "value": 5801.873721195871, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.798579381073, 505.2194359714241, 511.0596502435518, 529.5419462165228, 582.5019608234958, 30580.0, 41506.0, 42909.0, 43019.13961720768, 44879.55120448794, 37126.628733712656, 44002.55997440025, 44543.77728111363, 44615.77692111535, 44867.55132448675, 8484.448510846805, 9528.190103841182, 9776.169025632795, 9779.364341317827, 9790.0, 8555.358348123884, 11379.601713940014, 11637.941810290937, 11652.708682282926, 11848.0, 11565.959063684266, 11650.883491165065, 11667.6499705009, 11688.29870207786, 11760.647180584554, 8491.532965686885, 8689.0, 11527.077833773319, 11630.534778608846, 11643.35961522116, 11254.54981800727, 11616.244944078693, 11621.6513504595, 11833.763324733543, 11839.585614503538, 40299.59700402988, 40776.59223407758, 40793.184136317104, 40832.79583602093, 40886.38670419946, 3216.5014422764507, 3282.097423208637, 3359.81521016344, 3387.119348969261, 4087.9575708194516, 28399.716002839912, 28678.856605717046, 28783.42433151325, 29190.854045729637, 29406.264843379086, 2590.7279735627676, 2597.4805038992276, 2603.557395242813, 2626.947461050768, 2718.619393284922, 468492.0, 469414.65292673657, 469732.0, 469943.0, 470465.0, 29025.12924612265, 60365.0, 61391.693041534505, 61454.0, 61560.692196539625, 30379.544306835414, 36915.07712307214, 36941.0, 36977.44533831994, 37049.814750926074, 15161.317740701696, 15364.923175384052, 15379.231038448033, 15388.999715018488, 15398.0, 10999.890001100046, 11762.58830940913, 11971.64085077449, 12831.35843207836, 12945.54690585825, 8042.557659328793, 8088.919110808934, 8100.432969692183, 8167.63245653947, 8185.222403871623, 2905.259158914463, 3342.782719123249, 3351.2292172800394, 3372.3929692655065, 3379.712724418403, 920.7606022434215, 953.4327075390132, 1016.588281745894, 1063.6596289187385, 1162.4768854015726, 28152.71847281542, 29375.706242937726, 29517.11448656544, 29559.26101847428, 30302.84848575743, 1075.5858994287255, 1131.9434028298551, 1145.8109411946934, 1164.1036401970434, 1166.5742004168417, 15163.393464261306, 15171.544853654408, 15354.232288385536, 15570.0, 15672.451464198699, 732232.0, 740119.0, 742185.4453638594, 742314.0, 742586.0, 61567.0, 62019.68990155111, 62056.68971655203, 62058.37941620616, 62186.689066554376, 1306.3141850528548, 1368.0697125954248, 1429.907056041354, 1465.6116129225763, 1483.0730793254215, 23277.650835237822, 27422.0, 27937.720622793513, 28340.0, 30935.69064309418, 50429.99140017253, 51084.48915510946, 51105.97788044293, 51275.48724512856, 51278.46164614918, 462532.0, 557784.0, 558041.0, 605411.0, 923825.0, 34325.828370857984, 39240.803795980835, 40625.984350391474, 41716.3742543856, 41980.37029444622, 40586.79706601448, 46571.76714116408, 46703.766481167375, 48174.75912620555, 48197.75901120472, 36768.44847327239, 38022.0, 38233.0, 38984.41523377208, 39028.80485597554, 31568.684313157493, 32167.195820104684, 36873.0, 36898.815505923376, 37336.87989360323, 31488.842555787076, 33627.0, 38704.0, 39049.60950390573, 39164.6083539161, 27026.1892143236, 27557.31106722348, 32423.513647295782, 33081.50377744288, 33820.661793381754, 24776.380590485383, 24892.37769055788, 25059.0, 25255.36861578475, 25530.361740956625], "value": 19113.06698660323, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.49625486867075, 392.90373858404683, 393.13902553408053, 395.94060890866365, 410.67966985751127, 13889.861101388982, 20068.0, 21282.57434851302, 21287.25494607689, 21331.720096794204, 15822.892397532178, 17970.37103701371, 20386.0, 20946.790532094674, 21120.366389008355, 6000.12998115272, 6139.416755408229, 6151.90772138416, 6187.628742275478, 6243.65659888706, 6027.367126451725, 6116.571839971204, 6164.0, 6165.414285642857, 6167.845803854894, 5912.940870591304, 6053.061775424816, 6083.209182806251, 6087.512998960073, 6089.238845144357, 5910.911336329937, 5922.052471604523, 6055.515558755289, 6056.757729690807, 6067.939320606803, 5293.947060529384, 5314.840554783363, 5319.6808191508635, 5326.387465441447, 5328.147496400557, 25520.489590208093, 25777.52003479525, 26801.0, 26879.0, 27011.48850649403, 1854.962900741991, 2140.818030467412, 2322.396176993994, 2901.2166714987047, 2972.6432828060556, 14167.433302667881, 15022.023568468014, 15363.4622788203, 15783.210839457981, 16095.0, 1536.8847336449699, 1854.6754317994432, 1887.4337698690476, 1902.077492416177, 1902.5243689077731, 105659.47170264176, 106739.0, 116504.8349516511, 119863.0, 120597.0, 9691.466969316614, 11903.523859045541, 11924.284542927453, 11958.342291174069, 12088.697782555506, 15352.309146088453, 15420.151891646066, 15427.5371738848, 15466.5360039199, 15466.922665386826, 7567.545947243183, 7695.461317707706, 8753.737387878375, 8916.910830891738, 8940.105989401138, 8465.068842427328, 8713.520756358334, 8719.65121395137, 8865.024847266795, 8885.622728477096, 3973.7019723520852, 4735.644826638016, 4743.644226683013, 4745.288206768943, 4795.856124316276, 1935.7677078750357, 1973.9802601973797, 1978.8317992970758, 1982.3755517012096, 2010.5375763574168, 876.4522173641474, 947.5120313038791, 948.1608776233084, 1011.6610935336745, 1039.7868436970402, 15745.763813542806, 16094.356225751073, 20449.48876278105, 20727.481812954495, 21114.15543378248, 898.5237823953315, 927.1516562345379, 937.4000639590668, 992.1814503035025, 1027.4759872465097, 8208.794780130545, 9584.396936821908, 10967.451627418597, 11322.660320190407, 11495.770084598262, 500866.0, 529021.0, 530485.0, 642120.0, 644934.0, 144966.27516862558, 151696.2415187917, 155578.0, 155604.0, 158732.0, 860.3676297921023, 1008.1430783833682, 1015.1016350529834, 1016.8322226832638, 1018.9388636681823, 15369.615759605653, 17695.646087078443, 19280.517987049876, 19302.4209273722, 19339.033048347806, 52105.0, 57131.71434142803, 57147.42852571588, 57415.545523175235, 57619.71190144023, 81656.59171704104, 84085.15914841018, 85286.57356713177, 86150.56924715337, 86175.70736439084, 7951.6482198025315, 8197.877031844408, 8326.50040997542, 8338.624761885609, 8408.705695300636, 10385.480725963822, 11341.09271258281, 11697.941510292394, 12380.876191237972, 12796.552120675731, 7788.070210481681, 10573.2598718089, 11065.280756750786, 11091.94454027757, 11288.379139147426, 10669.626563070255, 10793.568257269935, 10916.126709863032, 11096.833547496955, 11212.607558735404, 11183.0, 11260.662180134608, 11394.487248074023, 11607.955284024478, 11639.53441862316, 8269.793255168668, 8332.04181519133, 8350.164983501843, 8353.707620233263, 8506.843240711569, 9987.500624968578, 10308.484575771032, 10595.046445819913, 11358.0913526916, 11423.0], "value": 9743.577520069122, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1448.847870973547, 1611.1460925709373, 1626.7478540826173, 1878.2017642501942, 1987.5428651410175, 8137.349012079027, 8950.55247237638, 9174.724758257245, 9176.22002129818, 9295.30285228609, 8850.0, 9109.498977556232, 9110.45337279762, 9111.362204645677, 9118.08819118088, 2220.0, 2294.678744975705, 2467.814913881457, 2500.899964001442, 2539.00978618339, 2538.200466852945, 2582.909598164065, 2584.547704151775, 2590.883410246534, 2594.0272397850804, 2485.751424857518, 2541.9110331138413, 2543.6438898554215, 2547.3758929062315, 2549.9362515937155, 2200.2409168836725, 2423.8303318767694, 2486.651868738369, 2503.574392353304, 2507.6489291499206, 2524.0534799450206, 2529.734377890313, 2549.757773011561, 2552.6043463263222, 2559.9360015999564, 9060.637574497012, 9207.217386522154, 9214.078592140731, 9220.631174753002, 9241.768955776091, 5084.949150508484, 5544.6118771686, 5722.399148089432, 5734.053881109612, 5735.598508104435, 3601.567811862567, 3613.8012409317475, 3615.9096022599383, 3701.5002974598356, 3709.5161935225856, 957.25812495316, 970.1026550440814, 1024.329064462778, 1029.12524354299, 1044.7022598559374, 12520.0, 12526.060545459128, 12527.373631318489, 12529.18560293578, 12551.372431378486, 4978.477259887732, 5008.348914641073, 5115.5396014358885, 5124.590032797368, 5131.666441681279, 10508.264421490423, 10516.947415262875, 10528.473576321307, 10546.947265263625, 10560.630377936735, 5589.636673616284, 5675.829725108253, 5680.573956953245, 5695.601307908407, 5703.315602127771, 4639.721616703008, 4791.0, 5037.622178336639, 5162.8967420652125, 5169.017886601609, 4108.110269276107, 4175.436316097288, 4191.140816132685, 4222.915541689211, 4245.68157388191, 1143.4568579924469, 1364.8089267502558, 1370.9314534273246, 1374.1342953938954, 1378.5795332423706, 648.8053583924898, 812.3095368936357, 822.43663090783, 832.5305835200875, 906.7279816055157, 5705.914411283834, 5879.746488830398, 5951.285845698544, 5977.163197152402, 6008.669523176267, 961.1061712607342, 963.3593660215878, 983.041534503853, 983.8671779309701, 1002.0830939690247, 3344.5652065231366, 3768.3970564709516, 3803.3154032274456, 3948.861789837342, 3949.8025098744947, 11084.057855082408, 11131.443427828575, 11236.887631123747, 11475.311481311148, 11475.77048459026, 10450.320729152581, 11399.430028498542, 11429.142814288962, 11433.599824006285, 11477.827832582518, 8033.638486268132, 8273.710420135267, 8330.042045164762, 8336.583170841555, 8344.165583441607, 5964.165016897725, 6035.426634469718, 6050.122732203894, 6201.255849298113, 6286.402791734778, 10113.0, 10410.739731506774, 10542.367457952547, 10550.8417373741, 10588.894111058791, 10710.839337410102, 10726.570937162738, 10731.678049658523, 10732.839007415052, 10779.137668986305, 7677.808054798675, 7725.690972361042, 7731.381489480716, 7887.092984306878, 7892.684292628231, 8447.40868139249, 8504.957475212585, 8557.657693692183, 8573.914260857311, 8601.956990215009, 6015.887060893749, 6934.757283495053, 7388.815279618053, 7440.069991251094, 7553.546787192786, 7450.776476705708, 7606.239376062349, 7671.0, 7676.846463070819, 7816.765497035098, 7260.019897314006, 7325.890111648436, 7339.009233753375, 7350.7427240046345, 7352.595607241653, 4893.779779909842, 5088.363954505687, 5144.948550514447, 5158.329417175743, 5164.638475306692, 5031.84904452867, 5131.9992601443855, 5160.793568257228, 5181.585473162213, 5246.3704355477585], "value": 4749.294659553195, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.2303521818717, 319.62124882014814, 324.7596778383997, 328.62372583392033, 333.72967896004224, 4531.977340113299, 5416.5625468539165, 5428.348598168215, 5434.864128396792, 5450.901402959859, 4945.145570411096, 5360.892782144365, 5368.731563421833, 5394.595405344595, 5403.594730395226, 3221.935561288767, 3482.860685572574, 3968.7420317679403, 4092.2224777292367, 4220.599043090901, 4139.834406623731, 4146.087860670647, 4149.232392007473, 4154.106867023589, 4171.8539851105215, 3496.737744669147, 3886.261610294035, 4109.773962432065, 4111.129436106565, 4136.958630413703, 3449.4653328734084, 3584.62361452049, 4098.8360465581345, 4113.321301985169, 4125.432335712425, 3282.868685252587, 3286.277019055804, 3533.6289689582736, 3546.1666508370513, 3573.624769399202, 3738.7195960302815, 4034.636882680573, 5116.0, 5134.8202812901745, 5157.226416037587, 4198.727082739612, 4239.364095385686, 4286.464191976003, 4308.9138217235795, 4440.622547083502, 5461.344638643349, 5547.44525547446, 5558.777648894039, 5575.163725441175, 5596.27248457702, 3821.5287114460853, 3838.6929045676557, 3854.2484215577974, 3864.574896761354, 3869.1487872667967, 5131.589472842164, 5532.585056120847, 5698.886022279573, 5836.328822185418, 5872.559558033165, 3894.805259737002, 3923.862664806718, 3930.194310166409, 3931.980340098281, 3938.8424463021156, 3987.1427643056304, 3997.360422332413, 3997.9805149687227, 4020.6381425671243, 4026.275270451288, 4632.351470794091, 4639.350490931272, 4642.396488456547, 4649.000464899995, 4670.225314380582, 4622.560856718606, 5183.533481986639, 5611.5230205433, 5631.662100273997, 5667.149927510906, 1150.551284998851, 1331.4674130347744, 1350.1628990026247, 1353.2421843767522, 1353.6345186799706, 731.974380896666, 814.8859159717645, 834.61190546395, 851.9478444121505, 869.7953334631454, 5150.072986862326, 5220.616536617799, 5250.632455728062, 5253.658512196695, 5262.60530460217, 4858.80564777412, 4881.95118048822, 5100.38795344561, 5744.874396473248, 5792.478676919098, 4042.4542686737504, 5178.326817513699, 5180.430152683201, 5217.869553261199, 5323.813666521653, 2582.2253324002722, 3006.083144640862, 3049.4968330225265, 3058.6788387219467, 3071.6314042315066, 5830.621009634445, 5890.852728681817, 5939.613925094855, 6031.276246850406, 6031.336552979169, 5582.385937546866, 5749.798757043483, 5896.968030594715, 5970.761169553256, 5990.850228744316, 5687.146927960839, 6015.285643591643, 6018.368071352533, 6040.637561746222, 6077.422644848821, 4526.592606665416, 4611.423572053493, 5113.463086376026, 5369.946300537101, 5754.036671114296, 4615.423072115986, 5439.265699130567, 5704.942950570441, 5733.0, 5785.4214578541805, 5232.581393488588, 5580.581456390706, 5635.323761148525, 5658.943410565842, 5766.077427611562, 2844.5448728203387, 3000.5949196858146, 3043.710847469487, 3096.3342881280632, 3730.6756101584633, 4628.375169352094, 4630.72215667061, 5778.27771528559, 5787.016207244695, 5806.9419305806405, 5580.414056524007, 5588.776448941997, 5619.342294023191, 5624.915626265528, 5655.632383895034, 4138.5654506277615, 4769.95230047709, 4967.577755890645, 5667.611435198402, 5683.2895888014, 5077.0, 5269.973650131725, 5477.370102438271, 5511.173324001432, 5520.613557050968, 5239.580833533384, 5511.063119269814, 5532.391436941853, 5659.264295641705, 5661.688607126647, 4478.888027799331, 4640.744759038285, 5326.147816349366, 5504.174373843955, 5543.61194716366], "value": 4043.818665602711, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [646.8900286951218, 650.9511786616002, 661.5666738286421, 674.578388507183, 730.4667146366277, 14706.705865882675, 15278.0, 15349.693006139898, 15357.46248881288, 15374.769378459332, 13635.250061246628, 14032.929835350835, 14044.929775351135, 14050.64873378166, 14057.086289391182, 889.5107690770083, 897.6274845938927, 907.3512438606396, 907.4419232172199, 908.0919080919081, 716.6631683108931, 735.7130719019586, 886.1271647427279, 904.9411788233776, 910.9498977556232, 963.3786207895903, 971.9659811906585, 972.0716715536646, 973.5521660036392, 973.9415635061919, 959.5729900194416, 961.0629636104812, 961.2310151878469, 961.7691753979065, 963.1812958984842, 551.9061759500854, 689.7723751162105, 709.2092317066433, 709.4643544124204, 713.5112447973155, 14957.177355245458, 14999.400023999027, 15001.024933379296, 15006.699866002728, 15036.548903532912, 12030.939845300805, 12219.2668439893, 12249.326287054208, 12357.0, 12539.0, 9899.45552994585, 10912.0, 11126.554937802479, 11691.123165762601, 11761.823572646417, 4153.91692166158, 4181.790910454495, 4299.6775241856985, 4305.526392096866, 4411.382406463098, 87938.56030719806, 89375.10624893667, 89988.0, 90502.0, 90711.54644226802, 9709.66016189444, 9856.162226210852, 9951.55218015191, 10074.899251007542, 10240.385576865412, 18310.450686479428, 18313.3590324338, 22123.557528849335, 22139.225127120473, 22142.22502212415, 6658.83352916181, 7159.785206443711, 7443.627818609048, 7543.962280188564, 7550.962245188739, 3990.1620659661216, 4074.144429669743, 4080.0925443235014, 4086.5096188457574, 4130.297849365615, 3438.5186073949667, 3527.153483164022, 3533.1343820763564, 3533.4169861973, 3536.7701099428455, 624.3537938233997, 634.0299342006737, 639.9136116624289, 646.1599920103846, 661.1141070964834, 489.52516059422305, 513.1712284660247, 546.4889580310445, 580.8925348810399, 612.3784358875745, 7927.16764739694, 7968.920310796933, 8070.950776399148, 8307.08622051574, 8426.0, 551.2778260478785, 580.9099589563624, 592.950477654549, 605.5367643752533, 607.781198768443, 3216.9223310190882, 3814.8855534334016, 3858.884233473, 3979.2041591681777, 3991.640752332304, 86892.0, 92890.53554732318, 94104.0, 94302.52848735849, 96968.51515742377, 80380.59809700915, 80446.5977670108, 82565.17434825575, 83541.0, 83762.3247535046, 72379.63810180915, 75393.0, 75670.6216468914, 75892.86160707593, 76040.61979690066, 7842.960785196037, 8158.757446128099, 8276.680157100478, 8757.47455152693, 8947.463152210888, 45059.09881802411, 45310.77344613256, 45721.54278457306, 46079.76960115178, 46307.53692463032, 44137.11725765403, 49431.75284123556, 62585.0, 63295.05057424234, 66467.0, 10096.596136154765, 11613.883861161281, 11797.233179843282, 11891.345975971404, 11944.20836874441, 11874.321132823678, 12003.459844307201, 12046.277223366626, 12164.513419463123, 12571.497140114294, 11514.251573648022, 11617.419129043345, 11890.702732431413, 11891.464884080064, 11895.405229738652, 11246.550137994716, 11456.656300311004, 11459.541618335174, 11473.196876218584, 11655.475503602527, 11251.043661288555, 11342.829857552308, 11648.475818588015, 11905.761884762083, 12571.0, 9369.297302702462, 9574.425534467955, 9763.41419514803, 9912.603495860085, 10140.645077422254, 6782.626955517494, 9492.72122025135, 9785.36395134314, 9798.559064841957, 9860.06329398706], "value": 6923.510782315541, "unit": "score"}], "name": "dromaeo_css", "value": 7237.156850941099, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 109.0, 112.0, 112.0, 112.0, 112.0, 113.0, 112.0, 113.0, 111.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 125.0, 134.0, 229.0, 140.0, 162.0, 140.0, 117.0, 166.0, 117.0], "value": 149.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 187.0, 185.0, 185.0, 185.0, 185.0, 186.0, 184.0, 183.0, 187.0], "value": 185.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 57.0, 123.0, 57.0, 81.0, 82.0, 82.0, 114.0, 56.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 101.0, 147.0, 113.0, 111.0, 77.0, 104.0, 79.0, 79.0, 102.0], "value": 100.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 92.0, 88.0, 91.0, 88.0, 92.0, 88.0, 92.0, 88.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 122.0, 88.0, 142.0, 145.0, 96.0, 97.0, 97.0, 96.0, 96.0], "value": 107.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 84.0, 83.0, 83.0, 85.0, 83.0, 84.0, 83.0], "value": 83.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 58.0, 58.0, 58.0, 55.0, 56.0, 117.0, 55.0, 54.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 68.0, 67.0, 73.0, 70.0, 67.0, 65.0, 68.0, 67.0, 68.0], "value": 67.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 83.0, 85.0, 85.0, 85.0, 85.0, 85.0, 86.0, 86.0, 85.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 124.0, 145.0, 128.0, 137.0, 146.0, 146.0, 148.0, 211.0, 139.0], "value": 146.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 168.0, 163.0, 165.0, 162.0, 167.0, 163.0, 160.0, 165.0, 163.0], "value": 163.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 66.0, 66.0, 67.0, 74.0, 82.0, 84.0, 70.0, 66.0], "value": 70.7, "unit": "score"}], "name": "kraken", "value": 1503.8, "alertThreshold": 2.0}]} 20:46:27 INFO - Return code: 0 20:46:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 20:46:27 ERROR - # TBPL SUCCESS # 20:46:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:46:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:46:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:46:27 INFO - 2016-05-18 20:46:27.518 screenresolution[1996:11016] starting screenresolution argv=screenresolution get 20:46:27 INFO - 2016-05-18 20:46:27.521 screenresolution[1996:11016] Display 0: 1600x1200x32@60 20:46:27 INFO - 2016-05-18 20:46:27.527 screenresolution[1997:11019] starting screenresolution argv=screenresolution list 20:46:27 INFO - Available Modes on Display 0 20:46:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:46:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:46:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:46:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:46:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:46:27 INFO - Intel Iris: 20:46:27 INFO - Chipset Model: Intel Iris 20:46:27 INFO - Type: GPU 20:46:27 INFO - Bus: Built-In 20:46:27 INFO - VRAM (Dynamic, Max): 1536 MB 20:46:27 INFO - Vendor: Intel (0x8086) 20:46:27 INFO - Device ID: 0x0a2e 20:46:27 INFO - Revision ID: 0x0009 20:46:27 INFO - Displays: 20:46:27 INFO - Display: 20:46:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:46:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:46:27 INFO - Display Serial Number: 1600x1200 60 20:46:27 INFO - Main Display: Yes 20:46:27 INFO - Mirror: Off 20:46:27 INFO - Online: Yes 20:46:27 INFO - Rotation: Supported 20:46:27 INFO - Return code: 0 20:46:27 INFO - Running post-action listener: _resource_record_post_action 20:46:27 INFO - ##### 20:46:27 INFO - ##### Finished run-tests step (success) 20:46:27 INFO - ##### 20:46:27 INFO - Running post-run listener: _resource_record_post_run 20:46:27 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 1380864; Write bytes: 692137984; Read time: 19; Write time: 1982 20:46:27 INFO - TinderboxPrint: CPU usage
53.6% 20:46:27 INFO - TinderboxPrint: I/O read bytes / time
1,380,864 / 19 20:46:27 INFO - TinderboxPrint: I/O write bytes / time
692,137,984 / 1,982 20:46:27 INFO - TinderboxPrint: CPU idle
2,255.7 (46.4%) 20:46:27 INFO - TinderboxPrint: CPU system
971.0 (20.0%) 20:46:27 INFO - TinderboxPrint: CPU user
1,635.0 (33.6%) 20:46:27 INFO - TinderboxPrint: Swap in / out
396,308,480 / 0 20:46:27 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 396306944; Write bytes: 397780992; Read time: 31502; Write time: 728 20:46:27 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 1356288; Write bytes: 293509120; Read time: 18; Write time: 1251 20:46:28 INFO - Running post-run listener: _upload_blobber_files 20:46:28 INFO - Blob upload gear active. 20:46:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:46:28 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 20:46:28 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'] 20:46:28 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 20:46:28 INFO - (blobuploader) - INFO - Open directory for files ... 20:46:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:46:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:46:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:46:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:46:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:46:29 INFO - (blobuploader) - INFO - Done attempting. 20:46:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:46:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:46:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:46:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:46:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:46:30 INFO - (blobuploader) - INFO - Done attempting. 20:46:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:46:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:46:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:46:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:46:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:46:32 INFO - (blobuploader) - INFO - Done attempting. 20:46:32 INFO - (blobuploader) - INFO - Iteration through files over. 20:46:32 INFO - Return code: 0 20:46:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:46:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:46:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f7f7f8e4668e93d359ed956db8e3227ab5b62985fe89334330a7daf1acf41cd42fe17483bc8b4eadd04fdee0549574f2f7f1257385be0eee7ca0bd03d1e23f27", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7c2b85c5ec60d655170cf45984ddf20af4cc671ba3657fda254b6a10015a0037105f1d6c86544a038f84eb6c2a247b14e303baf006068948fe6a2028f487e7de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5155de5b92b2da1bc756fbfc88a7fd20b4027dc7c96aa97072d32f870515ad5f1769a7c595508ebf585731cee1902d1db9ba3ef9983aebc95e360bad3af19dce"} 20:46:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:46:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:46:32 INFO - Contents: 20:46:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f7f7f8e4668e93d359ed956db8e3227ab5b62985fe89334330a7daf1acf41cd42fe17483bc8b4eadd04fdee0549574f2f7f1257385be0eee7ca0bd03d1e23f27", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7c2b85c5ec60d655170cf45984ddf20af4cc671ba3657fda254b6a10015a0037105f1d6c86544a038f84eb6c2a247b14e303baf006068948fe6a2028f487e7de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5155de5b92b2da1bc756fbfc88a7fd20b4027dc7c96aa97072d32f870515ad5f1769a7c595508ebf585731cee1902d1db9ba3ef9983aebc95e360bad3af19dce"} 20:46:32 INFO - Running post-run listener: copy_logs_to_upload_dir 20:46:32 INFO - Copying logs to upload dir... 20:46:32 INFO - mkdir: /builds/slave/test/build/upload/logs 20:46:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.194491 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-05-18 20:46:32.812895) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 20:46:32.815998) ========= 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.xnLqKhodRx/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.CB8CiSxLRw/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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/f7f7f8e4668e93d359ed956db8e3227ab5b62985fe89334330a7daf1acf41cd42fe17483bc8b4eadd04fdee0549574f2f7f1257385be0eee7ca0bd03d1e23f27", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7c2b85c5ec60d655170cf45984ddf20af4cc671ba3657fda254b6a10015a0037105f1d6c86544a038f84eb6c2a247b14e303baf006068948fe6a2028f487e7de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5155de5b92b2da1bc756fbfc88a7fd20b4027dc7c96aa97072d32f870515ad5f1769a7c595508ebf585731cee1902d1db9ba3ef9983aebc95e360bad3af19dce"} build_url:https://queue.taskcluster.net/v1/task/VuLPxXqwTzaarGrh_Vf60g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011052 build_url: 'https://queue.taskcluster.net/v1/task/VuLPxXqwTzaarGrh_Vf60g/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/f7f7f8e4668e93d359ed956db8e3227ab5b62985fe89334330a7daf1acf41cd42fe17483bc8b4eadd04fdee0549574f2f7f1257385be0eee7ca0bd03d1e23f27", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7c2b85c5ec60d655170cf45984ddf20af4cc671ba3657fda254b6a10015a0037105f1d6c86544a038f84eb6c2a247b14e303baf006068948fe6a2028f487e7de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5155de5b92b2da1bc756fbfc88a7fd20b4027dc7c96aa97072d32f870515ad5f1769a7c595508ebf585731cee1902d1db9ba3ef9983aebc95e360bad3af19dce"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-18 20:46:32.844138) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:46:32.844468) ========= 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.xnLqKhodRx/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.CB8CiSxLRw/Listeners TMPDIR=/var/folders/z2/7q46m13s59z0dctdqhn070sr00000w/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.005151 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-18 20:46:32.893173) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 20:46:32.893497) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-18 20:46:32.893837) ========= ========= Total master_lag: 0.16 =========