builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0390 starttime: 1471309472.48 results: success (0) buildid: 20160815144333 builduid: 28afb5a3c2634df6bde9d75e4932fae5 revision: 794db78767b9f9ec7714c74cd3ae38b561196b6d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 18:04:32.475731) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-15 18:04:32.476159) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 18:04:32.476444) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-15 18:04:32.507881) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 18:04:32.508147) ========= 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.HWPm30HWRz/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.hCsngWTAFF/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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-08-15 18:04:32-- 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% 147M=0s 2016-08-15 18:04:32 (147 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.454734 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 18:04:32.985002) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 18:04:32.985336) ========= 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.HWPm30HWRz/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.hCsngWTAFF/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.025492 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 18:04:33.034281) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-08-15 18:04:33.034635) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 794db78767b9f9ec7714c74cd3ae38b561196b6d --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 794db78767b9f9ec7714c74cd3ae38b561196b6d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HWPm30HWRz/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.hCsngWTAFF/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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-08-15 18:04:33,122 truncating revision to first 12 chars 2016-08-15 18:04:33,122 Setting DEBUG logging. 2016-08-15 18:04:33,123 attempt 1/10 2016-08-15 18:04:33,123 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/794db78767b9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-15 18:04:34,231 unpacking tar archive at: fx-team-794db78767b9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.373549 ========= master_lag: 5.00 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 6 secs) (at 2016-08-15 18:04:39.407381) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 18:04:39.407667) ========= script_repo_revision: 794db78767b9f9ec7714c74cd3ae38b561196b6d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-15 18:04:39.408015) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 18:04:39.408276) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-15 18:04:39.923357) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-15 18:04:39.923639) ========= /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.HWPm30HWRz/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.hCsngWTAFF/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 18:04:40 INFO - MultiFileLogger online at 20160815 18:04:40 in /builds/slave/test 18:04:40 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 18:04:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:04:40 INFO - {'append_to_log': False, 18:04:40 INFO - 'base_work_dir': '/builds/slave/test', 18:04:40 INFO - 'blob_upload_branch': 'Fx-Team', 18:04:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:04:40 INFO - 'branch': 'Fx-Team', 18:04:40 INFO - 'buildbot_json_path': 'buildprops.json', 18:04:40 INFO - 'config_files': ('talos/mac_config.py',), 18:04:40 INFO - 'default_actions': ('clobber', 18:04:40 INFO - 'read-buildbot-config', 18:04:40 INFO - 'download-and-extract', 18:04:40 INFO - 'populate-webroot', 18:04:40 INFO - 'create-virtualenv', 18:04:40 INFO - 'install', 18:04:40 INFO - 'run-tests'), 18:04:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:04:40 INFO - 'download_minidump_stackwalk': True, 18:04:40 INFO - 'download_symbols': 'ondemand', 18:04:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:04:40 INFO - 'tooltool.py': '/tools/tooltool.py', 18:04:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:04:40 INFO - '/tools/misc-python/virtualenv.py')}, 18:04:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:04:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:04:40 INFO - 'installer_path': 'installer.exe', 18:04:40 INFO - 'log_level': 'info', 18:04:40 INFO - 'log_name': 'talos', 18:04:40 INFO - 'log_to_console': True, 18:04:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:04:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:04:40 INFO - 'opt_config_files': (), 18:04:40 INFO - 'pip_index': False, 18:04:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:04:40 INFO - 'cmd': ('bash', 18:04:40 INFO - '-c', 18:04:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:04:40 INFO - 'enabled': True, 18:04:40 INFO - 'halt_on_failure': False, 18:04:40 INFO - 'name': 'check_screen_resolution'},), 18:04:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:04:40 INFO - 'cmd': ('bash', 18:04:40 INFO - '-c', 18:04:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:04:40 INFO - 'enabled': True, 18:04:40 INFO - 'halt_on_failure': False, 18:04:40 INFO - 'name': 'check_screen_resolution'},), 18:04:40 INFO - 'run_cmd_checks_enabled': True, 18:04:40 INFO - 'sps_profile': False, 18:04:40 INFO - 'sps_profile_interval': 0, 18:04:40 INFO - 'suite': 'dromaeojs', 18:04:40 INFO - 'system_bits': '32', 18:04:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:04:40 INFO - 'title': 't-yosemite-r7-0390', 18:04:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:04:40 INFO - 'use_talos_json': True, 18:04:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:04:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:04:40 INFO - 'work_dir': 'build'} 18:04:40 INFO - [mozharness: 2016-08-16 01:04:40.128081Z] Running clobber step. 18:04:40 INFO - Running pre-action listener: _resource_record_pre_action 18:04:40 INFO - Running main action method: clobber 18:04:40 INFO - rmtree: /builds/slave/test/build 18:04:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:04:42 INFO - Running post-action listener: _resource_record_post_action 18:04:42 INFO - [mozharness: 2016-08-16 01:04:42.152061Z] Finished clobber step (success) 18:04:42 INFO - [mozharness: 2016-08-16 01:04:42.152291Z] Running read-buildbot-config step. 18:04:42 INFO - Running pre-action listener: _resource_record_pre_action 18:04:42 INFO - Running main action method: read_buildbot_config 18:04:42 INFO - Using buildbot properties: 18:04:42 INFO - { 18:04:42 INFO - "project": "", 18:04:42 INFO - "product": "firefox", 18:04:42 INFO - "script_repo_revision": "production", 18:04:42 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 18:04:42 INFO - "repository": "", 18:04:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 18:04:42 INFO - "buildid": "20160815144333", 18:04:42 INFO - "pgo_build": "False", 18:04:42 INFO - "basedir": "/builds/slave/test", 18:04:42 INFO - "buildnumber": 87, 18:04:42 INFO - "slavename": "t-yosemite-r7-0390", 18:04:42 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 18:04:42 INFO - "platform": "yosemite_r7", 18:04:42 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 18:04:42 INFO - "branch": "fx-team", 18:04:42 INFO - "repo_path": "integration/fx-team", 18:04:42 INFO - "revision": "794db78767b9f9ec7714c74cd3ae38b561196b6d", 18:04:42 INFO - "stage_platform": "macosx64", 18:04:42 INFO - "builduid": "28afb5a3c2634df6bde9d75e4932fae5", 18:04:42 INFO - "slavebuilddir": "test" 18:04:42 INFO - } 18:04:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:04:42 INFO - Running post-action listener: _resource_record_post_action 18:04:42 INFO - [mozharness: 2016-08-16 01:04:42.157835Z] Finished read-buildbot-config step (success) 18:04:42 INFO - [mozharness: 2016-08-16 01:04:42.158046Z] Running download-and-extract step. 18:04:42 INFO - Running pre-action listener: _resource_record_pre_action 18:04:42 INFO - Running main action method: download_and_extract 18:04:42 INFO - mkdir: /builds/slave/test/build/tests 18:04: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:42 INFO - trying https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:04:42 INFO - Downloading https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:04:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 18:04:43 INFO - Downloaded 1199 bytes. 18:04:43 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:04:43 INFO - Using the following test package requirements: 18:04:43 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:04:43 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:04:43 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:04:43 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:04:43 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:04:43 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:04:43 INFO - u'jsshell-mac.zip'], 18:04:43 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:04:43 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:04:43 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:04:43 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:04:43 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:04:43 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:04:43 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:04:43 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:04:43 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:04:43 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:04:43 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:04:43 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:04:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:43 INFO - trying https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:04:43 INFO - Downloading https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:04:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:04:44 INFO - Downloaded 16330419 bytes. 18:04:44 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:04:51 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:04:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:51 INFO - trying https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:04:51 INFO - Downloading https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:04:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:04:52 INFO - Downloaded 16330419 bytes. 18:04:52 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:04: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:58 INFO - trying https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:04:58 INFO - Downloading https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:04:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:04:59 INFO - Downloaded 14413719 bytes. 18:04:59 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 18:04:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:59 INFO - trying https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:04:59 INFO - Downloading https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:04:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 18:05:02 INFO - Downloaded 111048679 bytes. 18:05:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:05:02 INFO - mkdir: /builds/slave/test/properties 18:05:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:05:02 INFO - Writing to file /builds/slave/test/properties/build_url 18:05:02 INFO - Contents: 18:05:02 INFO - build_url:https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:05:03 INFO - Running post-action listener: _resource_record_post_action 18:05:03 INFO - Running post-action listener: set_extra_try_arguments 18:05:03 INFO - [mozharness: 2016-08-16 01:05:03.347417Z] Finished download-and-extract step (success) 18:05:03 INFO - [mozharness: 2016-08-16 01:05:03.347559Z] Running populate-webroot step. 18:05:03 INFO - Running pre-action listener: _resource_record_pre_action 18:05:03 INFO - Running main action method: populate_webroot 18:05:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:05:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:05:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:05:03 INFO - '--tppagecycles', 18:05:03 INFO - '7'], 18:05:03 INFO - 'tests': ['tsvgm']}}, 18:05:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:05:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:05:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:05:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:05:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:05:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:05:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:03 INFO - 'talos_options': ['--disable-e10s'], 18:05:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:05:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:05:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:03 INFO - 'talos_options': ['--disable-e10s'], 18:05:03 INFO - 'tests': ['damp', 'tps']}, 18:05:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:03 INFO - 'tests': ['damp', 'tps']}, 18:05:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:05:03 INFO - 'tests': ['dromaeo_dom']}, 18:05:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:05:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:05:03 INFO - 'tests': ['basic_compositor_video']}, 18:05:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:05:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:05:03 INFO - 'tests': ['a11yr', 18:05:03 INFO - 'ts_paint', 18:05:03 INFO - 'tpaint', 18:05:03 INFO - 'sessionrestore', 18:05:03 INFO - 'sessionrestore_no_auto_restore', 18:05:03 INFO - 'tabpaint']}, 18:05:03 INFO - 'other-e10s': {'tests': ['a11yr', 18:05:03 INFO - 'ts_paint', 18:05:03 INFO - 'tpaint', 18:05:03 INFO - 'sessionrestore', 18:05:03 INFO - 'sessionrestore_no_auto_restore', 18:05:03 INFO - 'tabpaint']}, 18:05:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:05:03 INFO - 'ts_paint', 18:05:03 INFO - 'tpaint', 18:05:03 INFO - 'sessionrestore', 18:05:03 INFO - 'sessionrestore_no_auto_restore', 18:05:03 INFO - 'tabpaint']}, 18:05:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:05:03 INFO - 'ts_paint', 18:05:03 INFO - 'tpaint', 18:05:03 INFO - 'sessionrestore', 18:05:03 INFO - 'sessionrestore_no_auto_restore', 18:05:03 INFO - 'tabpaint']}, 18:05:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:05:03 INFO - 'tests': ['a11yr', 18:05:03 INFO - 'ts_paint', 18:05:03 INFO - 'tpaint', 18:05:03 INFO - 'sessionrestore', 18:05:03 INFO - 'sessionrestore_no_auto_restore', 18:05:03 INFO - 'tabpaint']}, 18:05:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:05:03 INFO - 'tests': ['a11yr', 18:05:03 INFO - 'ts_paint', 18:05:03 INFO - 'tpaint', 18:05:03 INFO - 'sessionrestore', 18:05:03 INFO - 'sessionrestore_no_auto_restore', 18:05:03 INFO - 'tabpaint']}, 18:05:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:05:03 INFO - 'tests': ['tsvgx', 18:05:03 INFO - 'tsvgr_opacity', 18:05:03 INFO - 'tart', 18:05:03 INFO - 'tscrollx', 18:05:03 INFO - 'cart']}, 18:05:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:05:03 INFO - 'tsvgr_opacity', 18:05:03 INFO - 'tart', 18:05:03 INFO - 'tscrollx', 18:05:03 INFO - 'cart']}, 18:05:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:03 INFO - 'talos_options': ['--disable-e10s'], 18:05:03 INFO - 'tests': ['tp5o']}, 18:05:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:03 INFO - 'tests': ['tp5o']}, 18:05:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:03 INFO - 'talos_options': ['--disable-e10s', 18:05:03 INFO - '--xperf_path', 18:05:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:05:03 INFO - 'tests': ['tp5n']}, 18:05:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:05:03 INFO - 'talos_options': ['--xperf_path', 18:05:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:05:03 INFO - 'tests': ['tp5n']}}, 18:05:03 INFO - 'talos.zip': {'path': '', 18:05:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:05:03 INFO - Running post-action listener: _resource_record_post_action 18:05:03 INFO - [mozharness: 2016-08-16 01:05:03.366172Z] Finished populate-webroot step (success) 18:05:03 INFO - [mozharness: 2016-08-16 01:05:03.366307Z] Running create-virtualenv step. 18:05:03 INFO - Running pre-action listener: _resource_record_pre_action 18:05:03 INFO - Running main action method: create_virtualenv 18:05:03 INFO - Creating virtualenv /builds/slave/test/build/venv 18:05:03 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 18:05:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:05:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:05:03 INFO - Using real prefix '/tools/python27' 18:05:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:05:04 INFO - Installing distribute.............................................................................................................................................................................................done. 18:05:07 INFO - Installing pip.................done. 18:05:07 INFO - Return code: 0 18:05:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057e6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10587b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8252c69660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10574ad30>, '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 0x1054afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105742b20>, '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.HWPm30HWRz/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.hCsngWTAFF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 18:05: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', 'pip>=1.5'] in /builds/slave/test/build 18:05: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 pip>=1.5 18:05:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HWPm30HWRz/Render', 18:05:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:07 INFO - 'HOME': '/Users/cltbld', 18:05:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:07 INFO - 'LOGNAME': 'cltbld', 18:05:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:07 INFO - 'MOZ_NO_REMOTE': '1', 18:05:07 INFO - 'NO_EM_RESTART': '1', 18:05:07 INFO - 'PAGER': '/bin/cat', 18:05:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:07 INFO - 'PWD': '/builds/slave/test', 18:05:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:07 INFO - 'SHELL': '/bin/bash', 18:05:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCsngWTAFF/Listeners', 18:05:07 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 18:05:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:07 INFO - 'USER': 'cltbld', 18:05:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:07 INFO - 'XPC_FLAGS': '0x0', 18:05:07 INFO - 'XPC_SERVICE_NAME': '0', 18:05:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:07 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 18:05:07 INFO - Cleaning up... 18:05:07 INFO - Return code: 0 18:05:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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', '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 0x1057e6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10587b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8252c69660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10574ad30>, '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 0x1054afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105742b20>, '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.HWPm30HWRz/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.hCsngWTAFF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 18:05: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', 'psutil>=3.1.1'] in /builds/slave/test/build 18:05: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 psutil>=3.1.1 18:05:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HWPm30HWRz/Render', 18:05:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:07 INFO - 'HOME': '/Users/cltbld', 18:05:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:07 INFO - 'LOGNAME': 'cltbld', 18:05:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:07 INFO - 'MOZ_NO_REMOTE': '1', 18:05:07 INFO - 'NO_EM_RESTART': '1', 18:05:07 INFO - 'PAGER': '/bin/cat', 18:05:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:07 INFO - 'PWD': '/builds/slave/test', 18:05:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:07 INFO - 'SHELL': '/bin/bash', 18:05:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCsngWTAFF/Listeners', 18:05:07 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 18:05:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:07 INFO - 'USER': 'cltbld', 18:05:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:07 INFO - 'XPC_FLAGS': '0x0', 18:05:07 INFO - 'XPC_SERVICE_NAME': '0', 18:05:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:07 INFO - Downloading/unpacking psutil>=3.1.1 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05: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 18:05:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:05:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:05:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:05:10 INFO - Installing collected packages: psutil 18:05:10 INFO - Running setup.py install for psutil 18:05:11 INFO - building 'psutil._psutil_osx' extension 18:05:11 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 18:05:11 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 18:05:11 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 18:05:11 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 18:05:11 INFO - building 'psutil._psutil_posix' extension 18:05:11 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 18:05:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:05:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:05:11 INFO - ^ 18:05:11 INFO - 1 warning generated. 18:05:11 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 18:05:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:05:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:05:12 INFO - Successfully installed psutil 18:05:12 INFO - Cleaning up... 18:05:12 INFO - Return code: 0 18:05:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:05:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:12 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 0x1057e6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10587b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8252c69660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10574ad30>, '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 0x1054afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105742b20>, '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.HWPm30HWRz/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.hCsngWTAFF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 18:05:12 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 18:05:12 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 18:05:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HWPm30HWRz/Render', 18:05:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:12 INFO - 'HOME': '/Users/cltbld', 18:05:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:12 INFO - 'LOGNAME': 'cltbld', 18:05:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:12 INFO - 'MOZ_NO_REMOTE': '1', 18:05:12 INFO - 'NO_EM_RESTART': '1', 18:05:12 INFO - 'PAGER': '/bin/cat', 18:05:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:12 INFO - 'PWD': '/builds/slave/test', 18:05:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:12 INFO - 'SHELL': '/bin/bash', 18:05:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCsngWTAFF/Listeners', 18:05:12 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 18:05:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:12 INFO - 'USER': 'cltbld', 18:05:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:12 INFO - 'XPC_FLAGS': '0x0', 18:05:12 INFO - 'XPC_SERVICE_NAME': '0', 18:05:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:05:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:05:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:05:14 INFO - Installing collected packages: mozsystemmonitor 18:05:14 INFO - Running setup.py install for mozsystemmonitor 18:05:15 INFO - Successfully installed mozsystemmonitor 18:05:15 INFO - Cleaning up... 18:05:15 INFO - Return code: 0 18:05:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:05:15 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:15 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:15 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057e6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10587b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8252c69660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10574ad30>, '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 0x1054afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105742b20>, '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.HWPm30HWRz/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.hCsngWTAFF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 18:05:15 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==2.5.1'] in /builds/slave/test/build 18:05:15 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==2.5.1 18:05:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HWPm30HWRz/Render', 18:05:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:15 INFO - 'HOME': '/Users/cltbld', 18:05:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:15 INFO - 'LOGNAME': 'cltbld', 18:05:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:15 INFO - 'MOZ_NO_REMOTE': '1', 18:05:15 INFO - 'NO_EM_RESTART': '1', 18:05:15 INFO - 'PAGER': '/bin/cat', 18:05:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:15 INFO - 'PWD': '/builds/slave/test', 18:05:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:15 INFO - 'SHELL': '/bin/bash', 18:05:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCsngWTAFF/Listeners', 18:05:15 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 18:05:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:15 INFO - 'USER': 'cltbld', 18:05:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:15 INFO - 'XPC_FLAGS': '0x0', 18:05:15 INFO - 'XPC_SERVICE_NAME': '0', 18:05:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:15 INFO - Downloading/unpacking jsonschema==2.5.1 18:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:05:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:05:17 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 18:05:17 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 18:05:17 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 18:05:17 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 18:05:17 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 18:05:17 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 18:05:18 INFO - Downloading functools32-3.2.3-2.tar.gz 18:05:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:05:18 INFO - warning: no files found matching '*.txt' 18:05:18 INFO - no previously-included directories found matching 'build' 18:05:18 INFO - no previously-included directories found matching 'dist' 18:05:18 INFO - no previously-included directories found matching '.git*' 18:05:18 INFO - Installing collected packages: jsonschema, functools32 18:05:18 INFO - Running setup.py install for functools32 18:05:18 INFO - warning: no files found matching '*.txt' 18:05:18 INFO - no previously-included directories found matching 'build' 18:05:18 INFO - no previously-included directories found matching 'dist' 18:05:18 INFO - no previously-included directories found matching '.git*' 18:05:18 INFO - Successfully installed jsonschema functools32 18:05:18 INFO - Cleaning up... 18:05:18 INFO - Return code: 0 18:05:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:05:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1057e6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10587b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8252c69660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10574ad30>, '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 0x1054afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105742b20>, '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.HWPm30HWRz/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.hCsngWTAFF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 18:05:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:05:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HWPm30HWRz/Render', 18:05:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:18 INFO - 'HOME': '/Users/cltbld', 18:05:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:18 INFO - 'LOGNAME': 'cltbld', 18:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:18 INFO - 'MOZ_NO_REMOTE': '1', 18:05:18 INFO - 'NO_EM_RESTART': '1', 18:05:18 INFO - 'PAGER': '/bin/cat', 18:05:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:18 INFO - 'PWD': '/builds/slave/test', 18:05:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:18 INFO - 'SHELL': '/bin/bash', 18:05:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCsngWTAFF/Listeners', 18:05:18 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 18:05:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:18 INFO - 'USER': 'cltbld', 18:05:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:18 INFO - 'XPC_FLAGS': '0x0', 18:05:18 INFO - 'XPC_SERVICE_NAME': '0', 18:05:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:05:18 INFO - Cleaning up... 18:05:18 INFO - Return code: 0 18:05:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:05:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1057e6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10587b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8252c69660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10574ad30>, '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 0x1054afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105742b20>, '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.HWPm30HWRz/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.hCsngWTAFF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 18:05:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:05:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HWPm30HWRz/Render', 18:05:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:18 INFO - 'HOME': '/Users/cltbld', 18:05:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:18 INFO - 'LOGNAME': 'cltbld', 18:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:18 INFO - 'MOZ_NO_REMOTE': '1', 18:05:18 INFO - 'NO_EM_RESTART': '1', 18:05:18 INFO - 'PAGER': '/bin/cat', 18:05:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:18 INFO - 'PWD': '/builds/slave/test', 18:05:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:18 INFO - 'SHELL': '/bin/bash', 18:05:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCsngWTAFF/Listeners', 18:05:18 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 18:05:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:18 INFO - 'USER': 'cltbld', 18:05:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:18 INFO - 'XPC_FLAGS': '0x0', 18:05:18 INFO - 'XPC_SERVICE_NAME': '0', 18:05:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:19 INFO - Downloading/unpacking blobuploader==1.2.4 18:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:21 INFO - Downloading blobuploader-1.2.4.tar.gz 18:05:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:05:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:05:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:22 INFO - Downloading docopt-0.6.1.tar.gz 18:05:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:05:22 INFO - Installing collected packages: blobuploader, requests, docopt 18:05:22 INFO - Running setup.py install for blobuploader 18:05:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:05:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:05:22 INFO - Running setup.py install for requests 18:05:23 INFO - Running setup.py install for docopt 18:05:23 INFO - Successfully installed blobuploader requests docopt 18:05:23 INFO - Cleaning up... 18:05:23 INFO - Return code: 0 18:05:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05:23 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:23 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:23 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 0x1057e6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10587b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8252c69660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10574ad30>, '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 0x1054afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105742b20>, '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.HWPm30HWRz/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.hCsngWTAFF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 18:05:23 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 18:05:23 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 18:05:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HWPm30HWRz/Render', 18:05:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:23 INFO - 'HOME': '/Users/cltbld', 18:05:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:23 INFO - 'LOGNAME': 'cltbld', 18:05:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:23 INFO - 'MOZ_NO_REMOTE': '1', 18:05:23 INFO - 'NO_EM_RESTART': '1', 18:05:23 INFO - 'PAGER': '/bin/cat', 18:05:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:23 INFO - 'PWD': '/builds/slave/test', 18:05:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:23 INFO - 'SHELL': '/bin/bash', 18:05:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCsngWTAFF/Listeners', 18:05:23 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 18:05:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:23 INFO - 'USER': 'cltbld', 18:05:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:23 INFO - 'XPC_FLAGS': '0x0', 18:05:23 INFO - 'XPC_SERVICE_NAME': '0', 18:05:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:05:23 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-dFowZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:05:24 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-dBYbrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:05:24 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-Vo61xv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:05:24 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-HrIlzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:05:24 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-HSrabY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:05:24 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-pRADAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:05:24 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-AnYkkq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:05:24 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-diEZ2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:05:24 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-9RBBMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:05:24 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-lh3Ndh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:05:24 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-RS3Ndp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:05:25 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-ZeITc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:05:25 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-V4YNo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:05:25 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-_LCdl6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:25 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-GpoOvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:05:25 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-JxXYxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:05:25 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-nQBfVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:05:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:05:25 INFO - Running setup.py install for manifestparser 18:05:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:05:25 INFO - Running setup.py install for mozcrash 18:05:26 INFO - Running setup.py install for mozdebug 18:05:26 INFO - Running setup.py install for mozdevice 18:05:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:05:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:05:26 INFO - Running setup.py install for mozfile 18:05:26 INFO - Running setup.py install for mozhttpd 18:05:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:05:26 INFO - Running setup.py install for mozinfo 18:05:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:05:26 INFO - Running setup.py install for mozInstall 18:05:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:05:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:05:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:05:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:05:26 INFO - Running setup.py install for mozleak 18:05:27 INFO - Running setup.py install for mozlog 18:05:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:05:27 INFO - Running setup.py install for moznetwork 18:05:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:05:27 INFO - Running setup.py install for mozprocess 18:05:27 INFO - Running setup.py install for mozprofile 18:05:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:05:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:05:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:05:27 INFO - Running setup.py install for mozrunner 18:05:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:05:27 INFO - Running setup.py install for mozscreenshot 18:05:28 INFO - Running setup.py install for moztest 18:05:28 INFO - Running setup.py install for mozversion 18:05:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:05:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:05:28 INFO - Cleaning up... 18:05:28 INFO - Return code: 0 18:05:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:28 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 0x1057e6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10587b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8252c69660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10574ad30>, '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 0x1054afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105742b20>, '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.HWPm30HWRz/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.hCsngWTAFF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 18:05:28 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 18:05:28 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 18:05:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HWPm30HWRz/Render', 18:05:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:28 INFO - 'HOME': '/Users/cltbld', 18:05:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:28 INFO - 'LOGNAME': 'cltbld', 18:05:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:28 INFO - 'MOZ_NO_REMOTE': '1', 18:05:28 INFO - 'NO_EM_RESTART': '1', 18:05:28 INFO - 'PAGER': '/bin/cat', 18:05:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:28 INFO - 'PWD': '/builds/slave/test', 18:05:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:28 INFO - 'SHELL': '/bin/bash', 18:05:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCsngWTAFF/Listeners', 18:05:28 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 18:05:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:28 INFO - 'USER': 'cltbld', 18:05:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:28 INFO - 'XPC_FLAGS': '0x0', 18:05:28 INFO - 'XPC_SERVICE_NAME': '0', 18:05:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:05:28 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-1LwbEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:05:28 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)) 18:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:05:28 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-HR67_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:05:28 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)) 18:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:05:28 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-XLHSYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:05:28 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)) 18:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:05:28 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-1DfAzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:05:29 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)) 18:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:05:29 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-an7S0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:05:29 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)) 18:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:05:29 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-OFjEsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:05:29 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)) 18:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:05:29 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-Cv5afk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:05:29 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)) 18:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:05:29 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-kw8UzV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:05:29 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)) 18:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:05:29 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-hTDYfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:05:29 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)) 18:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:05:29 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-JO4eby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 18:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:05:29 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-L3GQsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:05:29 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)) 18:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:05:29 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-WYdeFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 18:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:05:29 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-7lz58X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:05:30 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)) 18:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:05:30 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-je9DHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:05:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 18:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:30 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-pqe7pm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:30 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)) 18:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:05:30 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-Lk6ogr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:05:30 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)) 18:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:05:30 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-FgsUFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:05:30 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)) 18:05:30 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)) 18:05:30 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)) 18:05:30 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)) 18:05:30 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)) 18:05:30 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)) 18:05:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:05:30 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 18:05:30 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 18:05:30 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 18:05:30 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 18:05:30 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 18:05:30 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 18:05:32 INFO - Downloading blessings-1.6.tar.gz 18:05:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:05:32 INFO - Installing collected packages: blessings 18:05:32 INFO - Running setup.py install for blessings 18:05:33 INFO - Successfully installed blessings 18:05:33 INFO - Cleaning up... 18:05:33 INFO - Return code: 0 18:05:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:05:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:05:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:05:33 INFO - Reading from file tmpfile_stdout 18:05:33 INFO - Current package versions: 18:05:33 INFO - blessings == 1.6 18:05:33 INFO - blobuploader == 1.2.4 18:05:33 INFO - docopt == 0.6.1 18:05:33 INFO - functools32 == 3.2.3-2 18:05:33 INFO - jsonschema == 2.5.1 18:05:33 INFO - manifestparser == 1.1 18:05:33 INFO - mozInstall == 1.12 18:05:33 INFO - mozcrash == 0.17 18:05:33 INFO - mozdebug == 0.1 18:05:33 INFO - mozdevice == 0.48 18:05:33 INFO - mozfile == 1.2 18:05:33 INFO - mozhttpd == 0.7 18:05:33 INFO - mozinfo == 0.9 18:05:33 INFO - mozleak == 0.1 18:05:33 INFO - mozlog == 3.3 18:05:33 INFO - moznetwork == 0.27 18:05:33 INFO - mozprocess == 0.23 18:05:33 INFO - mozprofile == 0.28 18:05:33 INFO - mozrunner == 6.12 18:05:33 INFO - mozscreenshot == 0.1 18:05:33 INFO - mozsystemmonitor == 0.3 18:05:33 INFO - moztest == 0.7 18:05:33 INFO - mozversion == 1.4 18:05:33 INFO - psutil == 3.1.1 18:05:33 INFO - requests == 1.2.3 18:05:33 INFO - wsgiref == 0.1.2 18:05:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:33 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 0x1057e6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10587b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8252c69660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10574ad30>, '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 0x1054afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105742b20>, '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.HWPm30HWRz/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.hCsngWTAFF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 18:05:33 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 18:05:33 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 18:05:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HWPm30HWRz/Render', 18:05:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:33 INFO - 'HOME': '/Users/cltbld', 18:05:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:33 INFO - 'LOGNAME': 'cltbld', 18:05:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:33 INFO - 'MOZ_NO_REMOTE': '1', 18:05:33 INFO - 'NO_EM_RESTART': '1', 18:05:33 INFO - 'PAGER': '/bin/cat', 18:05:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:33 INFO - 'PWD': '/builds/slave/test', 18:05:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:33 INFO - 'SHELL': '/bin/bash', 18:05:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCsngWTAFF/Listeners', 18:05:33 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 18:05:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:33 INFO - 'USER': 'cltbld', 18:05:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:33 INFO - 'XPC_FLAGS': '0x0', 18:05:33 INFO - 'XPC_SERVICE_NAME': '0', 18:05:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:33 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)) 18:05:33 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)) 18:05:33 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)) 18:05:33 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)) 18:05:33 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)) 18:05:33 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)) 18:05:33 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)) 18:05:33 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)) 18:05:33 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)) 18:05:33 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)) 18:05:33 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)) 18:05:33 INFO - Cleaning up... 18:05:33 INFO - Return code: 0 18:05:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:33 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 0x1057e6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10587b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8252c69660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10574ad30>, '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 0x1054afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105742b20>, '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.HWPm30HWRz/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.hCsngWTAFF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 18:05:33 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 18:05:33 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 18:05:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HWPm30HWRz/Render', 18:05:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:33 INFO - 'HOME': '/Users/cltbld', 18:05:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:33 INFO - 'LOGNAME': 'cltbld', 18:05:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:33 INFO - 'MOZ_NO_REMOTE': '1', 18:05:33 INFO - 'NO_EM_RESTART': '1', 18:05:33 INFO - 'PAGER': '/bin/cat', 18:05:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:33 INFO - 'PWD': '/builds/slave/test', 18:05:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:33 INFO - 'SHELL': '/bin/bash', 18:05:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCsngWTAFF/Listeners', 18:05:33 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 18:05:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:33 INFO - 'USER': 'cltbld', 18:05:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:33 INFO - 'XPC_FLAGS': '0x0', 18:05:33 INFO - 'XPC_SERVICE_NAME': '0', 18:05:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:05:33 INFO - Cleaning up... 18:05:33 INFO - Return code: 0 18:05:33 INFO - Running post-action listener: _resource_record_post_action 18:05:33 INFO - Running post-action listener: _start_resource_monitoring 18:05:34 INFO - Starting resource monitoring. 18:05:34 INFO - [mozharness: 2016-08-16 01:05:34.022608Z] Finished create-virtualenv step (success) 18:05:34 INFO - [mozharness: 2016-08-16 01:05:34.023601Z] Running install step. 18:05:34 INFO - Running pre-action listener: _resource_record_pre_action 18:05:34 INFO - Running main action method: install 18:05:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:05:34 INFO - Reading from file tmpfile_stdout 18:05:34 INFO - Detecting whether we're running mozinstall >=1.0... 18:05:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:05:34 INFO - Reading from file tmpfile_stdout 18:05:34 INFO - Output received: 18:05:34 INFO - Usage: mozinstall [options] installer 18:05:34 INFO - Options: 18:05:34 INFO - -h, --help show this help message and exit 18:05:34 INFO - -d DEST, --destination=DEST 18:05:34 INFO - Directory to install application into. [default: 18:05:34 INFO - "/builds/slave/test"] 18:05:34 INFO - --app=APP Application being installed. [default: firefox] 18:05:34 INFO - mkdir: /builds/slave/test/build/application 18:05:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:06:09 INFO - Reading from file tmpfile_stdout 18:06:09 INFO - Output received: 18:06:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:06:09 INFO - Running post-action listener: _resource_record_post_action 18:06:09 INFO - [mozharness: 2016-08-16 01:06:09.051707Z] Finished install step (success) 18:06:09 INFO - [mozharness: 2016-08-16 01:06:09.051853Z] Running run-tests step. 18:06:09 INFO - Running pre-action listener: _resource_record_pre_action 18:06:09 INFO - Running main action method: run_tests 18:06:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:06:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:06:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:06:09 INFO - 2016-08-15 18:06:09.066 screenresolution[1659:8183] starting screenresolution argv=screenresolution get 18:06:09 INFO - 2016-08-15 18:06:09.069 screenresolution[1659:8183] Display 0: 1600x1200x32@60 18:06:09 INFO - 2016-08-15 18:06:09.075 screenresolution[1660:8185] starting screenresolution argv=screenresolution list 18:06:09 INFO - Available Modes on Display 0 18:06:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:06:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:06:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:06:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:06:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:06:09 INFO - Intel Iris: 18:06:09 INFO - Chipset Model: Intel Iris 18:06:09 INFO - Type: GPU 18:06:09 INFO - Bus: Built-In 18:06:09 INFO - VRAM (Dynamic, Max): 1536 MB 18:06:09 INFO - Vendor: Intel (0x8086) 18:06:09 INFO - Device ID: 0x0a2e 18:06:09 INFO - Revision ID: 0x0009 18:06:09 INFO - Displays: 18:06:09 INFO - Display: 18:06:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:06:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:06:09 INFO - Display Serial Number: 1600x1200 60 18:06:09 INFO - Main Display: Yes 18:06:09 INFO - Mirror: Off 18:06:09 INFO - Online: Yes 18:06:09 INFO - Rotation: Supported 18:06:09 INFO - Return code: 0 18:06:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:06:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:06:09 INFO - Python 2.7.3 18:06:09 INFO - Return code: 0 18:06:09 INFO - grabbing minidump binary from tooltool 18:06:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:06:09 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 0x10574ad30>, '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 0x1054afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105742b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:06:09 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 18:06:09 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 18:06:09 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 18:06:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:06:09 INFO - Return code: 0 18:06:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:06:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:06:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:06:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:06:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:06:09 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/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--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 18:06:09 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/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0390 --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 18:06:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HWPm30HWRz/Render', 18:06:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:06:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:06:09 INFO - 'HOME': '/Users/cltbld', 18:06:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:06:09 INFO - 'LOGNAME': 'cltbld', 18:06:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:06:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:06:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:06:09 INFO - 'MOZ_NO_REMOTE': '1', 18:06:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:06:09 INFO - 'NO_EM_RESTART': '1', 18:06:09 INFO - 'PAGER': '/bin/cat', 18:06:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:06:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:06:09 INFO - 'PWD': '/builds/slave/test', 18:06:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:06:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:06:09 INFO - 'SHELL': '/bin/bash', 18:06:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hCsngWTAFF/Listeners', 18:06:09 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 18:06:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:06:09 INFO - 'USER': 'cltbld', 18:06:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:06:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:06:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:06:09 INFO - 'XPC_FLAGS': '0x0', 18:06:09 INFO - 'XPC_SERVICE_NAME': '0', 18:06:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:06:09 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/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--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 18:06:09 INFO - mozversion application_buildid: 20160815144333 18:06:09 INFO - mozversion application_changeset: 794db78767b9f9ec7714c74cd3ae38b561196b6d 18:06:09 INFO - mozversion application_display_name: Nightly 18:06:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:06:09 INFO - mozversion application_name: Firefox 18:06:09 INFO - mozversion application_remotingname: firefox 18:06:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:06:09 INFO - mozversion application_vendor: Mozilla 18:06:09 INFO - mozversion application_version: 51.0a1 18:06:09 INFO - mozversion platform_buildid: 20160815144333 18:06:09 INFO - mozversion platform_changeset: 794db78767b9f9ec7714c74cd3ae38b561196b6d 18:06:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:06:09 INFO - mozversion platform_version: 51.0a1 18:06:09 INFO - using testdate: 1471309569 18:06:09 INFO - actual date: 1471309569 18:06:09 INFO - starting webserver on 'localhost:49275' 18:06:09 INFO - SUITE-START | Running 2 tests 18:06:09 INFO - TEST-START | dromaeo_css 18:06:09 INFO - operating with platform_type : mac_ 18:06:09 INFO - Initialising browser for dromaeo_css test... 18:06:09 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpWX87rL/profile http://localhost:49275/getInfo.html) 18:06:12 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 18:06:12 INFO - PROCESS | 1670 | colorDepth:24 18:06:12 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 18:06:12 INFO - PROCESS | 1670 | __metrics 18:06:12 INFO - PROCESS | 1670 | 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" 18:06:12 INFO - PROCESS | 1670 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:06:12 INFO - PROCESS | 1670 | ************************* 18:06:12 INFO - PROCESS | 1670 | A coding exception was thrown in a Promise resolution callback. 18:06:12 INFO - PROCESS | 1670 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 18:06:12 INFO - PROCESS | 1670 | 18:06:12 ERROR - PROCESS | 1670 | Full message: TypeError: win is null 18:06:12 INFO - PROCESS | 1670 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:758:17 18:06:12 INFO - PROCESS | 1670 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 18:06:12 INFO - PROCESS | 1670 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 18:06:12 INFO - PROCESS | 1670 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 18:06:12 INFO - PROCESS | 1670 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 18:06:12 INFO - PROCESS | 1670 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 18:06:12 INFO - PROCESS | 1670 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 18:06:12 INFO - PROCESS | 1670 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 18:06:12 INFO - PROCESS | 1670 | 18:06:12 INFO - PROCESS | 1670 | ************************* 18:06:13 INFO - TEST-INFO | 1670: exit 0 18:06:13 INFO - Browser initialized. 18:06:13 INFO - Running cycle 1/1 for dromaeo_css test... 18:06:13 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpWX87rL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:06:24 INFO - PROCESS | 1673 | RSS: Main: 248274944 18:06:25 INFO - PROCESS | 1673 | 18:07:16 INFO - PROCESS | 1673 | 1471309636796 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] 18:09:28 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 18:09:29 INFO - PROCESS | 1673 | RSS: Main: 373248000 18:09:29 INFO - PROCESS | 1673 | 18:12:32 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 18:12:33 INFO - PROCESS | 1673 | RSS: Main: 388165632 18:12:33 INFO - PROCESS | 1673 | 18:15:35 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 18:15:36 INFO - PROCESS | 1673 | RSS: Main: 355913728 18:15:36 INFO - PROCESS | 1673 | 18:18:38 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 18:18:39 INFO - PROCESS | 1673 | RSS: Main: 368451584 18:18:39 INFO - PROCESS | 1673 | 18:21:42 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 18:21:43 INFO - PROCESS | 1673 | RSS: Main: 346742784 18:21:43 INFO - PROCESS | 1673 | 18:24:46 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 18:24:47 INFO - PROCESS | 1673 | RSS: Main: 444321792 18:24:47 INFO - PROCESS | 1673 | 18:24:47 INFO - PROCESS | 1673 | __start_tp_report 18:24:47 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 18:24:47 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 18:24:47 INFO - PROCESS | 1673 | |i|pagename|runs| 18:24:47 INFO - PROCESS | 1673 | |0;dojo.html;3398.541196938413;3406.829658517075;3416.624171341152;3438.071720635427;3440.088376580207;5126.41046279678;5490.560755139584;5680.176374425706;5799;5849.034909239976;5780.335261444935;5836.037053886114;5933.110033494978;5946.494547963417;5953.642781433117;5220.112580861243;5229.8169564065265;5288.814891478799;5304.522592966632;5341.519263266306;5384.703841288728;5388.353397592295;5398.082326004569;5399;5399.244105825188;5166.535011848914;5246.658967167122;5252.264682944391;5304.840854774363;5309.62832601718;5286.894262114774;5293.914747476758;5300.575953923677;5304.73476326186;5307.150855863082;4856.125897338477;5054.812119151997;5218.243354713583;5275.973620131913;5287.048331300364;5745.942540574624;5820.708964551798;5861.677607731573;5888.411158884121;5894.469497745224;6292.307846136921;6352;6352.364763523657;6357.364263573651;6367.108604795332;6238.688065596702;6257.405546473078;6295.653739044351;6316.2736285326855;6323.810285691437;5872.06047032477;5943.167956486095;5963.403659634002;5969.04495280753;5981.850453738604;6342.556021078481;6353.714082866282;6354.9682251589375;6361.586496877688;6381.425671689477;6107.8473038173515;6143.389059649631;6160.599561028519;6263.467605253604;6282.465990390868;6333.714982825784;6336.303006669263;6348.523860710465;6349.301576826545;6385.553011289165;6330.0821380899515;6330.873382532323;6333.429991300713;6347.206599175103;6353.777617783356;6140.87237156979;6233.470155036873;6298.0552917062805;6344.333844946306;6354.618722876642;5014.623903207201;5040.092783298968;5059.342285502935;5072.442031376546;5102.668326558762;4535.297028960516;4554.544545545419;4555.886102847389;4560.338750881104;4588.908221835544;5831.591788574759;5877.588568800143;5877.735501902424;5882.294124705063;5918.023526118229;5448.774025844154;6117.248930028882;6251.031090181029;6266.24805023391;6293.496520278367;5868.325142608569;5882.55880808941;5907.2911250650195;5922.378150294243;5925.881482370329;4721.480637129913;5457.20870473787;5517.97917385285;5531.612787104864;5534.418886016991;6173.783917562864;6259.874802504016;6347.079673447417;6347.206599175103;6367.554271200971;5343.9198412024625;6250.156228909221;6262.780802671885;6267.9373206267355;6278.215223097113;6427.871442571216;6430.7749228776765;6434.967825160844;6436.871262574816;6442.226932768098;6106.217989796568;6136.907946380898;6174.351693072163;6193.752249910133;6216.595921265103;6147.44672979434;6213.409726076015;6261.68691565429;6276.9372306276355;6285;5995.190649262294;6203.937960620517;6208.255009398901;6218.347073557211;6219.129321895029;6045.546584006129;6127.448529632355;6191.380861913773;6204.5036397089025;6224.564280500322;6015.518758499397;6072.715524739821;6081.222629011544;6085.398626455588;6110.961136606877;6148.9692551536955;6157.784477543443;6172.259328880384;6173.98129308659;6182.443580077815;5953.434423729739;6057.42454466825;6077.091620541051;6079.05774604937;6125.601835880653 18:24:47 INFO - PROCESS | 1673 | |1;ext.html;471.5802935387506;496.10205527994356;505.4591587001909;522.2166749875187;524.9553787928025;30733.69266307339;39952;41066.178676426454;41068.99999999996;43473.7826310868;42203.57796422035;42799.86401631801;42820.35769463461;42893;42964.57035429645;8770.210681038705;9725.854112188304;9939.850902236472;9942.10521053105;9945.254105942047;8923.018467968519;9020.54897255138;9033.999999999967;9043.773905652346;11860.822087668692;11994.040476761838;12235.265884046985;12247.571335003277;12248.081393895449;12265.325407102606;12062;12107.455164517618;12123.636290911287;12133.332666703327;12144.99999999991;11969.760604787856;12054;12063.638090857288;12073.637790866289;12090.153689241757;40230.59769402327;40516;40523.797381012904;40550;40576.18847623031;3631.4189729643394;3699.7965111918834;3715.294094122108;4008.2785098682225;4232.195882782262;24328.148514802073;31146.221344466343;31621.683783162334;32197;32589;3225.1130938991732;3237;3262.4616938205168;3265.3959017581824;3348.430766769655;493629;497694;500175;501815.98184018425;502436.99999999633;59753.701231493564;60047.69976150179;60293.69853150706;60370.39629603648;60373.39626603766;34615;34879.12802179915;35061.47407788885;36368;63046.42383940438;16279.430219942245;16454.83545164533;16457.506274811774;16510.091944942902;16530.421435249707;12835.93582032084;12932.67668308281;12949.676258093623;12953.417096230693;12955.287459189647;16523.752143717855;16818.24317905697;16836.24236909342;17304.913475432793;18775.96732179743;6536.771213007617;7822.960885195537;7888.2506161914525;7943.960280198562;7964.601769911481;822.3955392786297;937.2642475656577;948.170350942925;1029.8197815382277;1048.2033654422694;23313.88343058308;29973.700262997092;29990.40019199604;30748.077557673307;30755.15652026108;1001.67946257197;1073.7959787640323;1074.8441475985937;1110.7945030169444;1220.8901198892142;15057.397704091714;15550.688986220212;15568.299426525833;15607.531774046796;15940.760888586681;673441.2655873477;678136;678600;679485;681280;51658;57857.710711446176;58467.83064338689;59061.5234619138;59085.40914590885;1282.8011658192995;1297.7080156964796;1323.9669008274677;1327.8207441995207;1333.4266265505732;30310;30795.230119247197;31401.05796826099;31554;32666.83666581747;51711.48288517217;52248;52332.9533409322;52343.47656523538;52371.21443178279;439298;450393;450546;450689;451383;41090.1781964365;41772.79113604413;41970.58029419789;41996.37005444982;42276;40744.77765667035;45078.87302817456;46478.83802904955;46981;47063.529364705915;33042.17394565059;37903.62096379001;39091.804540977115;39139.80430097945;39824.80087599543;34507.65492345044;38203;38866.61133388743;39055.24300478417;39384.2123157541;37522.624773751915;37993.62006379901;38115.237695245385;38201.23597528089;38393;32721.018369448953;33567.244447109944;33983.320333593685;34289.48565771466;34432.82783586066;24976.37559061038;25089.87455062713;25304.367390815376;25485.984801295628;25728.228153155436 18:24:47 INFO - PROCESS | 1673 | |2;jquery.html;354.66129845997074;369.52700543304564;373.2423180942686;380.2926556604715;400.8757285241575;15335.69328613427;16255.34978600856;20298.391048268575;20875.37373878782;20964.89517552414;18042.278308867662;18415.539611509685;18431.262749490004;19147.234110635593;21337.25319613814;5625.662460252398;5868.73590688419;6132.233470816148;6133.447989680928;6143.477804386633;6609.966950165242;6763.222229443628;6797;6805.965970170142;6833.6241506717115;6064.393560643923;6457.128287681174;6699.464042876558;6712.899306510381;6725.394714475696;6408.647524386157;6655.203095164303;6698.464122870208;6698.665066746643;6720.59676419414;5702.0021496238;5779.855503612402;5781;5790.7104644768015;5812.88374232513;25488.362790930376;26166.084187053362;26178.476430471474;26434.6034809478;26849;2333.9533209335887;2360.586897292984;2542.9618555721677;2589.6374507568958;3015.9849200754074;9708.320417570772;10194.388336699822;10293.53679084443;10398;10421.583136674524;1811.7372980917826;1882.5670095877888;1886.6226754648987;1919.2418994497234;1934.303650685752;90062;92218;97404.51297743537;124927.75072249252;128815.35592321979;9680.322377433653;10805.297655652515;11271.041961433206;11288.435578221057;11491.42542872853;14578.271086445635;14582.19797911125;14592.343344549521;14612.48856290046;14624.85375146235;7437.256274372519;8352.958235208907;8507.276881465144;8652.307815374756;8759.211670949646;7086.610236437045;8515.403921725543;8548.487090774574;8711.60797764102;8721.389502734748;4466.935246143277;4517.628245171311;4551.112533056023;4608.400907882021;4668.603168730628;1755.1399814091003;1859.3399343232957;1901.543629528903;1903.8191371819655;1909.3699079303797;733.7615275035635;870.3428911172151;903.8101998580372;961.0965692249264;1054.541274545575;20963.79036209649;21673.783262167493;21702;21714.56570868574;21745.02147403339;865.4677373415304;906.9909300906907;933.6358820060182;949.2121539122512;985.8164166371522;10907.29102608328;11319.151063670257;11347.148963827745;11437.828432573519;11612.477438515287;497979;507452;582866;606663;606687;125593.74406256003;126710;132297;132565;132646;979.9069088436587;989.8465737810651;1018;1035.1046344911597;1035.9430231337199;18489.260429582668;19296.4211073668;19317.227310907405;19338.323158689378;21361.78638213598;38629.22741545097;49717;54554.63613409538;55514.889702206536;56742;68153.65923170352;74519.62740186264;79087.60456197683;80205;81250.59374703326;5430.592705547178;5553.916691249715;7610.505317154367;7932.36541076724;8451.45065570736;10992.945035275092;12827.743445131231;12838.743225135631;13281.269530175932;13305.268210248529;11304.434778261219;11310.886891130984;11466.656000320003;11536.59621913229;11791.757266751712;10906.890931090587;11153.219274650697;11207.887921120684;11575.652730418102;11654;10999.120070394189;11208.607698730506;11388.316700997966;11457;11754.70613234676;7718.382529397522;7826.699472201341;8187.426880118573;8402.705905293285;8491.83016339682;8209.220124088202;10425.478726063819;11043.171762118034;11821.172517924007;11821.881781182423 18:24:47 INFO - PROCESS | 1673 | |3;mootools.html;1457.934392952317;1539.7921280627113;1741.120734029315;1891.442024602742;1907.227572833003;8366.91633083669;8902;8908.955455222724;8912.554372281376;8931.821363572724;6897.5861448313135;7872.1734217907015;8099.757007289776;8168.224018718212;8730.650773969033;2173.206779525474;2206.6248737714545;2277.1005452846116;2359.7994170495444;2366.5740166769974;2356;2356.964645530318;2363.763623637631;2367.0886708617136;2370.8577485350934;2320.3155069254526;2377.0967032527615;2377.3699969508093;2379.4646204604;2385.451346190377;2328.092044102801;2341.976580234193;2360.9173678921156;2370.573296806574;2372.6203807390734;2326.4881726020417;2340.0756701103137;2344.4373350395954;2369.869657168855;2413.481101563155;8530.317574594017;8620.482771033758;8896.59965301563;8985.505797181153;9003.189712925869;5231.973840130813;5394.3796463406425;5398.838034858961;5406.783728650849;5406.91889621656;3485.686288234071;3607.801570913599;3628.6371362863765;3656.6891814195824;3693.2059607184315;1059.1050562274866;1089.2593036735018;1111.4553868604326;1116.972075698106;1119.832025196227;11728.296302221894;11742.647720568311;11751.294922304689;11768.176227664067;11782.410879455992;4684.1334353144775;4823.734694591761;4857.878553036202;4900.529841047672;5000.924986125211;9894.406335619886;10203.94898025505;10224.028717271847;10247.128994035442;10270.33242839228;5423.322084739408;5479.0959491684225;5482;5483.232347471358;5492.890142197134;4873.41519017713;4916.680415772963;4945.7527123644395;4989.825356112591;4999.0501804657;3689.4281386385155;3805.4291856221234;3971.1065010372436;4142.79286035697;4144;1146.7901364060995;1153.1985270237105;1274.6494713953552;1292.3279894454831;1310.4365122997203;774.9070111586645;841.3563623827777;872.1147288828381;925.6390007896791;947.1097168661436;5879.470847623735;5902.61632993854;6018.488428483628;6112.113743507167;6145.784897528565;929.5816882402946;980.1129977370491;997.8952210017989;1023.2785885950387;1041.906228439444;3578.964210357863;3899.1811719539214;3944.9211015780097;4010.2581022510344;4118.299889018874;10528.526216320284;10822.837657435144;10828.971247731453;10830.566777328977;10849.403282819521;10275.7431064224;10770.407627580402;10773.976472235125;10777.83833242502;10782.029617334478;7964.482308649919;8187.754367368989;8192.098869124391;8193.426460147732;8208.179182081863;5718.485336319584;5879.764809407576;5891.1457838614015;5955.910661339996;6095.420935011167;8564.871526926976;9619.855702164334;9894.713687220616;10000.649977250761;10139.7465063374;9686.321957463191;9987.60049598037;10124.59501619927;10300.175985920958;10301.536430860479;7507.211742766931;7587.317141457295;7674.079110506552;7678.347340475899;7703.114141873756;8197.71308004217;8200.302974247255;8267.049289331564;8298.460600060977;8307.086220515617;7397.704091836266;7511.3615342696485;7574.886376704464;7594.126675432396;7701.383889288732;7407.148177959388;7416.332530072319;7428.442866785121;7509.098908131059;7524.736634217776;6858.554193977376;7023.894641580278;7074.469414793808;7077;7082.3979961703835;4946.060248552763;4956.653034287565;5036.748162591783;5050.595952323879;5107.6169287302855;4858.441279252931;4963.404391473046;5026.899462010812;5059.3169922061525;5247.921281180709 18:24:47 INFO - PROCESS | 1673 | |4;prototype.html;288.8108289070658;301.8551095474172;308.27401469539234;312.30044699872263;314.8787716729059;4507.504174540798;5485.341758988927;5620.381758006621;5684.573656975723;5705.25831641887;5485.917711234335;5588.86027849304;5589.189567512708;5591.2731344925205;5605.859853503664;3614.8011859347725;3843.5586655004445;4183.247015537202;4242.387892600807;4327.545607711192;4316.007318316773;4320.870373888788;4332.003639162995;4337.262665346899;4341.348797680357;3254.4630136027527;4174.603412675791;4187.560306167854;4201.516825565069;4222.9155416891645;4206.137741762931;4226.387173859804;4227.8584782108655;4232.397075798377;4237.4279472271155;3400.9934138858193;3406.9268180523304;3634.327649384872;3650.4889315495584;3677.7425580209397;4551.886202844922;5017.498250174954;5340.305760251182;5548.889022219533;5556.277683901067;4737.976310118427;4742.407199100113;4757.833475828364;4765.571098601108;4777.355057067286;5122;5362.973185134049;5711.457411545896;5832.966857234257;5880.970595146997;3212.4378233809225;3667.431548110047;3878.1661942682176;3899.5320561532512;3920.882373528799;5890.145928840294;6015.3383127855905;6017.368176341465;6037.5849660135855;6091.329953705133;3856.1902000580194;3999.280489267326;4001.579834117434;4022.738521996061;4071.531773846046;3488.8081155536183;3712.2946640138825;3875.4961854958674;3898.3762597984187;4125.628693417607;4671.672982891233;4686.062787442525;4711.293306004127;4725.9527404726205;4831.275308703723;5761.9135712964335;5767.826965191051;5770.278715160605;5851.414858514199;5871.324797648325;1197.4012993503247;1301.4078594239597;1363.7681594129022;1388.2434073430004;1397.182648150843;825.6335764310077;830.8368284401886;837.5560952695081;843.4601854812925;856.4219152072326;3980.208905992315;4505.662075344362;4518.593326600621;4526.0537968673125;4616.630669546428;4974.477679843564;5040.97541475112;5054.321209758834;5129.358830146231;5139.177731562932;3964.147708242684;4630.53694630541;5026.396832380065;5501.752421141058;5578.888422231614;2698.5007773561956;3154.558361829346;3155.605549306337;3175.3490534440384;3177.3327601203546;5403.648762830403;5499.642523235977;5792.594518383757;6250.281217660027;6282.434580887742;5955.064604003716;6096;6104.870598939122;6172.969135154295;6227.875442491125;5696.401877802854;6184.690765461709;6197.597156184924;6318.304986451487;6333.398327158912;5423.535645375648;5544.695041772741;5962.850928726816;5978.521718262616;6085.391460853879;5573.627955087276;5893.852653683693;5901.200133959183;5911.970440147772;5916.49709774674;4625.028743963805;6027.397260273938;6062.696865156813;6203.627782333075;6243.937560624518;3640.872569460056;3925.175713100281;3944.802759861938;4050.23045621331;4064.6992962251798;5812.244408226903;5847.649141051551;5902.20320256777;6016.21815001772;6090.746423823214;5001.799928002839;5605.346422805201;5829.737661805314;5862.736176871966;5884.205632239593;5345.679259244458;5611;5803.332616748959;5817.563682723898;5843.707814609167;5047.671901326402;5494.890102197854;5622.606417550732;5701.230333905036;5737.34020587621;5370.382406023357;5599.832005039855;5759.787392409876;5826.912596311144;5869.4717475427415;5401.540869026019;5713.942860571341;5749.137629355463;5766.855828604318;5793.420657934173 18:24:47 INFO - PROCESS | 1673 | |5;yui.html;711.6548473990114;746.7535713214639;769.3076231391747;780.7579650308405;865.8225063861908;15378.692426151485;16296.185190740445;16301.429449969253;16324.428644997413;16341.83658163418;12637.49450021998;14756.6310842229;14894.851051489482;14929.477468288613;14962.32669529871;670.9194896612413;707.1937990690617;829.887965124708;861.3841103610916;863.848826455371;855.2943821347383;857.7641148684099;859.0893652728097;859.5315553023618;859.9699010534632;913.4108500017485;914.7027216154777;915.6428992692822;916.5829547555846;918.4581097152678;706.7561691216522;731.349099301622;733.8495608400265;734.9706011759523;912.3020889019905;540.824232124561;649.8407890066917;668.2014992084461;675.0852594734114;677.7898851356096;14572;14594.635134121625;14595.124292542376;14596.781048284285;14631.341589628386;11042.840501747361;12666.11337206396;12696.936515317364;12993.415296311689;13245.668858278525;12033.682316783672;12355.567555135616;12458.501659933592;12549.435275412628;12960.80558791619;4504.086227640556;4508.571685689854;4555.886102847422;4609.055143695535;4715.929261061086;103876;104750.47624761828;106128.46935765349;107170.46414767877;108011;10740.838887416696;10775.892241077489;10810.783784324269;10825.566977320976;11165.609203677832;20965.22625927317;20978.265760698607;21000.579988400146;21016.789832101484;21019.47451313699;6999.950007498814;7556.622168891534;7656;7925.167857374892;7986.520808751494;4315.201687687788;4326.783660816946;4462.955370446254;4513.255312873404;4581.748003859753;2960.3931194105157;3504.281622267429;3508.1580420698847;3511.3152935177905;3511.8244087795506;641.9358064193636;652.2043107408954;659.970301336441;660.5970358081566;661.0810972747885;517.3998162131909;518.225253246399;591.6213623281103;598.3238939997775;600.5075837813039;7256.2743725627015;8111.797205069803;8177.059638141571;8534.975802903691;9288.72133835986;588.5096712086751;637.2162240444187;639.2744235292884;645.5803727577019;646.4537465841356;4430.623397011225;4475.6419486441655;4540.121308541327;4548;4617.238155704271;103228;129248;164873.17563412347;169164;173230;107686.46156769323;111004.4449777762;142255.2887235557;144146;144215;105036;119926;123392.38303808424;124514.52810391255;125995.8501037463;8325.167483251626;8534.359923005675;8541.444806087584;8910.955445222991;8945.284377249674;65968;67484;67533.31166720872;68234.31765682479;68380.31619683938;77184;82872.58563707385;83605.58197208976;84459.73310400225;103305;11065.424659719041;11864.76270474603;13325.666858328232;13385.732285354434;13787.241701706502;12798.232106073665;12945.223286602833;12974.935125324313;13049.934750326187;13544.458221671417;13208.141470804367;13591.252481113634;13639.79540306916;14260.857391425952;14927.552173434815;14249.715005700034;14286.642833929234;14416.855831441551;14465.927670361581;14619.269036548343;13716.314184290546;13757.10578812374;13862.168269903435;13868.791968120688;13978.580642580739;10886;11609.593664221711;11745.941270293593;11829.231099978473;12117.15179937396;11526.769464610828;11671.07962702614;11723.706907327385;11725.941370293094;11943.402829858645 18:24:47 INFO - PROCESS | 1673 | __end_tp_report 18:24:47 INFO - PROCESS | 1673 | __start_cc_report 18:24:47 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1272 18:24:47 INFO - PROCESS | 1673 | __end_cc_report 18:24:47 INFO - PROCESS | 1673 | __startTimestamp1471310687053__endTimestamp 18:24:47 INFO - PROCESS | 1673 | 18:24:47 INFO - PROCESS | 1673 | ------- Summary: start ------- 18:24:47 INFO - PROCESS | 1673 | Number of tests: 6 18:24:47 INFO - PROCESS | 1673 | 18:24:47 INFO - PROCESS | 1673 | [#0] dojo.html Cycles:165 Average:5814.02 Median:6072.72 stddev:640.19 (10.5%) stddev-sans-first:613.47 18:24:47 INFO - PROCESS | 1673 | Values: 3398.5 3406.8 3416.6 3438.1 3440.1 5126.4 5490.6 5680.2 5799.0 5849.0 5780.3 5836.0 5933.1 5946.5 5953.6 5220.1 5229.8 5288.8 5304.5 5341.5 5384.7 5388.4 5398.1 5399.0 5399.2 5166.5 5246.7 5252.3 5304.8 5309.6 5286.9 5293.9 5300.6 5304.7 5307.2 4856.1 5054.8 5218.2 5276.0 5287.0 5745.9 5820.7 5861.7 5888.4 5894.5 6292.3 6352.0 6352.4 6357.4 6367.1 6238.7 6257.4 6295.7 6316.3 6323.8 5872.1 5943.2 5963.4 5969.0 5981.9 6342.6 6353.7 6355.0 6361.6 6381.4 6107.8 6143.4 6160.6 6263.5 6282.5 6333.7 6336.3 6348.5 6349.3 6385.6 6330.1 6330.9 6333.4 6347.2 6353.8 6140.9 6233.5 6298.1 6344.3 6354.6 5014.6 5040.1 5059.3 5072.4 5102.7 4535.3 4554.5 4555.9 4560.3 4588.9 5831.6 5877.6 5877.7 5882.3 5918.0 5448.8 6117.2 6251.0 6266.2 6293.5 5868.3 5882.6 5907.3 5922.4 5925.9 4721.5 5457.2 5518.0 5531.6 5534.4 6173.8 6259.9 6347.1 6347.2 6367.6 5343.9 6250.2 6262.8 6267.9 6278.2 6427.9 6430.8 6435.0 6436.9 6442.2 6106.2 6136.9 6174.4 6193.8 6216.6 6147.4 6213.4 6261.7 6276.9 6285.0 5995.2 6203.9 6208.3 6218.3 6219.1 6045.5 6127.4 6191.4 6204.5 6224.6 6015.5 6072.7 6081.2 6085.4 6111.0 6149.0 6157.8 6172.3 6174.0 6182.4 5953.4 6057.4 6077.1 6079.1 6125.6 18:24:47 INFO - PROCESS | 1673 | 18:24:47 INFO - PROCESS | 1673 | [#1] ext.html Cycles:180 Average:67938.81 Median:38054.43 stddev:147122.98 (386.6%) stddev-sans-first:147448.49 18:24:47 INFO - PROCESS | 1673 | Values: 471.6 496.1 505.5 522.2 525.0 30733.7 39952.0 41066.2 41069.0 43473.8 42203.6 42799.9 42820.4 42893.0 42964.6 8770.2 9725.9 9939.9 9942.1 9945.3 8923.0 9020.5 9034.0 9043.8 11860.8 11994.0 12235.3 12247.6 12248.1 12265.3 12062.0 12107.5 12123.6 12133.3 12145.0 11969.8 12054.0 12063.6 12073.6 12090.2 40230.6 40516.0 40523.8 40550.0 40576.2 3631.4 3699.8 3715.3 4008.3 4232.2 24328.1 31146.2 31621.7 32197.0 32589.0 3225.1 3237.0 3262.5 3265.4 3348.4 493629.0 497694.0 500175.0 501816.0 502437.0 59753.7 60047.7 60293.7 60370.4 60373.4 34615.0 34879.1 35061.5 36368.0 63046.4 16279.4 16454.8 16457.5 16510.1 16530.4 12835.9 12932.7 12949.7 12953.4 12955.3 16523.8 16818.2 16836.2 17304.9 18776.0 6536.8 7823.0 7888.3 7944.0 7964.6 822.4 937.3 948.2 1029.8 1048.2 23313.9 29973.7 29990.4 30748.1 30755.2 1001.7 1073.8 1074.8 1110.8 1220.9 15057.4 15550.7 15568.3 15607.5 15940.8 673441.3 678136.0 678600.0 679485.0 681280.0 51658.0 57857.7 58467.8 59061.5 59085.4 1282.8 1297.7 1324.0 1327.8 1333.4 30310.0 30795.2 31401.1 31554.0 32666.8 51711.5 52248.0 52333.0 52343.5 52371.2 439298.0 450393.0 450546.0 450689.0 451383.0 41090.2 41772.8 41970.6 41996.4 42276.0 40744.8 45078.9 46478.8 46981.0 47063.5 33042.2 37903.6 39091.8 39139.8 39824.8 34507.7 38203.0 38866.6 39055.2 39384.2 37522.6 37993.6 38115.2 38201.2 38393.0 32721.0 33567.2 33983.3 34289.5 34432.8 24976.4 25089.9 25304.4 25486.0 25728.2 18:24:47 INFO - PROCESS | 1673 | 18:24:47 INFO - PROCESS | 1673 | [#2] jquery.html Cycles:180 Average:33663.18 Median:26641.80 stddev:94034.56 (353.0%) stddev-sans-first:94265.09 18:24:47 INFO - PROCESS | 1673 | Values: 354.7 369.5 373.2 380.3 400.9 15335.7 16255.3 20298.4 20875.4 20964.9 18042.3 18415.5 18431.3 19147.2 21337.3 5625.7 5868.7 6132.2 6133.4 6143.5 6610.0 6763.2 6797.0 6806.0 6833.6 6064.4 6457.1 6699.5 6712.9 6725.4 6408.6 6655.2 6698.5 6698.7 6720.6 5702.0 5779.9 5781.0 5790.7 5812.9 25488.4 26166.1 26178.5 26434.6 26849.0 2334.0 2360.6 2543.0 2589.6 3016.0 9708.3 10194.4 10293.5 10398.0 10421.6 1811.7 1882.6 1886.6 1919.2 1934.3 90062.0 92218.0 97404.5 124927.8 128815.4 9680.3 10805.3 11271.0 11288.4 11491.4 14578.3 14582.2 14592.3 14612.5 14624.9 7437.3 8353.0 8507.3 8652.3 8759.2 7086.6 8515.4 8548.5 8711.6 8721.4 4466.9 4517.6 4551.1 4608.4 4668.6 1755.1 1859.3 1901.5 1903.8 1909.4 733.8 870.3 903.8 961.1 1054.5 20963.8 21673.8 21702.0 21714.6 21745.0 865.5 907.0 933.6 949.2 985.8 10907.3 11319.2 11347.1 11437.8 11612.5 497979.0 507452.0 582866.0 606663.0 606687.0 125593.7 126710.0 132297.0 132565.0 132646.0 979.9 989.8 1018.0 1035.1 1035.9 18489.3 19296.4 19317.2 19338.3 21361.8 38629.2 49717.0 54554.6 55514.9 56742.0 68153.7 74519.6 79087.6 80205.0 81250.6 5430.6 5553.9 7610.5 7932.4 8451.5 10992.9 12827.7 12838.7 13281.3 13305.3 11304.4 11310.9 11466.7 11536.6 11791.8 10906.9 11153.2 11207.9 11575.7 11654.0 10999.1 11208.6 11388.3 11457.0 11754.7 7718.4 7826.7 8187.4 8402.7 8491.8 8209.2 10425.5 11043.2 11821.2 11821.9 18:24:47 INFO - PROCESS | 1673 | 18:24:47 INFO - PROCESS | 1673 | [#3] mootools.html Cycles:180 Average:5734.56 Median:4960.03 stddev:3187.49 (64.3%) stddev-sans-first:3180.23 18:24:47 INFO - PROCESS | 1673 | Values: 1457.9 1539.8 1741.1 1891.4 1907.2 8366.9 8902.0 8909.0 8912.6 8931.8 6897.6 7872.2 8099.8 8168.2 8730.7 2173.2 2206.6 2277.1 2359.8 2366.6 2356.0 2357.0 2363.8 2367.1 2370.9 2320.3 2377.1 2377.4 2379.5 2385.5 2328.1 2342.0 2360.9 2370.6 2372.6 2326.5 2340.1 2344.4 2369.9 2413.5 8530.3 8620.5 8896.6 8985.5 9003.2 5232.0 5394.4 5398.8 5406.8 5406.9 3485.7 3607.8 3628.6 3656.7 3693.2 1059.1 1089.3 1111.5 1117.0 1119.8 11728.3 11742.6 11751.3 11768.2 11782.4 4684.1 4823.7 4857.9 4900.5 5000.9 9894.4 10203.9 10224.0 10247.1 10270.3 5423.3 5479.1 5482.0 5483.2 5492.9 4873.4 4916.7 4945.8 4989.8 4999.1 3689.4 3805.4 3971.1 4142.8 4144.0 1146.8 1153.2 1274.6 1292.3 1310.4 774.9 841.4 872.1 925.6 947.1 5879.5 5902.6 6018.5 6112.1 6145.8 929.6 980.1 997.9 1023.3 1041.9 3579.0 3899.2 3944.9 4010.3 4118.3 10528.5 10822.8 10829.0 10830.6 10849.4 10275.7 10770.4 10774.0 10777.8 10782.0 7964.5 8187.8 8192.1 8193.4 8208.2 5718.5 5879.8 5891.1 5955.9 6095.4 8564.9 9619.9 9894.7 10000.6 10139.7 9686.3 9987.6 10124.6 10300.2 10301.5 7507.2 7587.3 7674.1 7678.3 7703.1 8197.7 8200.3 8267.0 8298.5 8307.1 7397.7 7511.4 7574.9 7594.1 7701.4 7407.1 7416.3 7428.4 7509.1 7524.7 6858.6 7023.9 7074.5 7077.0 7082.4 4946.1 4956.7 5036.7 5050.6 5107.6 4858.4 4963.4 5026.9 5059.3 5247.9 18:24:47 INFO - PROCESS | 1673 | 18:24:47 INFO - PROCESS | 1673 | [#4] prototype.html Cycles:180 Average:4676.91 Median:5342.99 stddev:1461.88 (27.4%) stddev-sans-first:1428.39 18:24:47 INFO - PROCESS | 1673 | Values: 288.8 301.9 308.3 312.3 314.9 4507.5 5485.3 5620.4 5684.6 5705.3 5485.9 5588.9 5589.2 5591.3 5605.9 3614.8 3843.6 4183.2 4242.4 4327.5 4316.0 4320.9 4332.0 4337.3 4341.3 3254.5 4174.6 4187.6 4201.5 4222.9 4206.1 4226.4 4227.9 4232.4 4237.4 3401.0 3406.9 3634.3 3650.5 3677.7 4551.9 5017.5 5340.3 5548.9 5556.3 4738.0 4742.4 4757.8 4765.6 4777.4 5122.0 5363.0 5711.5 5833.0 5881.0 3212.4 3667.4 3878.2 3899.5 3920.9 5890.1 6015.3 6017.4 6037.6 6091.3 3856.2 3999.3 4001.6 4022.7 4071.5 3488.8 3712.3 3875.5 3898.4 4125.6 4671.7 4686.1 4711.3 4726.0 4831.3 5761.9 5767.8 5770.3 5851.4 5871.3 1197.4 1301.4 1363.8 1388.2 1397.2 825.6 830.8 837.6 843.5 856.4 3980.2 4505.7 4518.6 4526.1 4616.6 4974.5 5041.0 5054.3 5129.4 5139.2 3964.1 4630.5 5026.4 5501.8 5578.9 2698.5 3154.6 3155.6 3175.3 3177.3 5403.6 5499.6 5792.6 6250.3 6282.4 5955.1 6096.0 6104.9 6173.0 6227.9 5696.4 6184.7 6197.6 6318.3 6333.4 5423.5 5544.7 5962.9 5978.5 6085.4 5573.6 5893.9 5901.2 5912.0 5916.5 4625.0 6027.4 6062.7 6203.6 6243.9 3640.9 3925.2 3944.8 4050.2 4064.7 5812.2 5847.6 5902.2 6016.2 6090.7 5001.8 5605.3 5829.7 5862.7 5884.2 5345.7 5611.0 5803.3 5817.6 5843.7 5047.7 5494.9 5622.6 5701.2 5737.3 5370.4 5599.8 5759.8 5826.9 5869.5 5401.5 5713.9 5749.1 5766.9 5793.4 18:24:47 INFO - PROCESS | 1673 | 18:24:47 INFO - PROCESS | 1673 | [#5] yui.html Cycles:180 Average:24965.46 Median:11989.93 stddev:40284.90 (336.0%) stddev-sans-first:40356.75 18:24:47 INFO - PROCESS | 1673 | Values: 711.7 746.8 769.3 780.8 865.8 15378.7 16296.2 16301.4 16324.4 16341.8 12637.5 14756.6 14894.9 14929.5 14962.3 670.9 707.2 829.9 861.4 863.8 855.3 857.8 859.1 859.5 860.0 913.4 914.7 915.6 916.6 918.5 706.8 731.3 733.8 735.0 912.3 540.8 649.8 668.2 675.1 677.8 14572.0 14594.6 14595.1 14596.8 14631.3 11042.8 12666.1 12696.9 12993.4 13245.7 12033.7 12355.6 12458.5 12549.4 12960.8 4504.1 4508.6 4555.9 4609.1 4715.9 103876.0 104750.5 106128.5 107170.5 108011.0 10740.8 10775.9 10810.8 10825.6 11165.6 20965.2 20978.3 21000.6 21016.8 21019.5 7000.0 7556.6 7656.0 7925.2 7986.5 4315.2 4326.8 4463.0 4513.3 4581.7 2960.4 3504.3 3508.2 3511.3 3511.8 641.9 652.2 660.0 660.6 661.1 517.4 518.2 591.6 598.3 600.5 7256.3 8111.8 8177.1 8535.0 9288.7 588.5 637.2 639.3 645.6 646.5 4430.6 4475.6 4540.1 4548.0 4617.2 103228.0 129248.0 164873.2 169164.0 173230.0 107686.5 111004.4 142255.3 144146.0 144215.0 105036.0 119926.0 123392.4 124514.5 125995.9 8325.2 8534.4 8541.4 8911.0 8945.3 65968.0 67484.0 67533.3 68234.3 68380.3 77184.0 82872.6 83605.6 84459.7 103305.0 11065.4 11864.8 13325.7 13385.7 13787.2 12798.2 12945.2 12974.9 13049.9 13544.5 13208.1 13591.3 13639.8 14260.9 14927.6 14249.7 14286.6 14416.9 14465.9 14619.3 13716.3 13757.1 13862.2 13868.8 13978.6 10886.0 11609.6 11745.9 11829.2 12117.2 11526.8 11671.1 11723.7 11725.9 11943.4 18:24:47 INFO - PROCESS | 1673 | -------- Summary: end -------- 18:24:47 INFO - PROCESS | 1673 | 18:24:47 INFO - TEST-INFO | 1673: exit 0 18:24:47 INFO - TEST-OK | dromaeo_css | took 1118082ms 18:24:47 INFO - TEST-START | kraken 18:24:47 INFO - operating with platform_type : mac_ 18:24:47 INFO - Initialising browser for kraken test... 18:24:47 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpRMG33J/profile http://localhost:49275/getInfo.html) 18:24:49 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 18:24:49 INFO - PROCESS | 1679 | colorDepth:24 18:24:49 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 18:24:49 INFO - PROCESS | 1679 | __metrics 18:24:50 INFO - PROCESS | 1679 | 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" 18:24:50 INFO - PROCESS | 1679 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:24:50 INFO - PROCESS | 1679 | ************************* 18:24:50 INFO - PROCESS | 1679 | A coding exception was thrown in a Promise resolution callback. 18:24:50 INFO - PROCESS | 1679 | See https://developer.mozilla.org/Mozilla/JavaScript_code_modules/Promise.jsm/Promise 18:24:50 INFO - PROCESS | 1679 | 18:24:50 ERROR - PROCESS | 1679 | Full message: TypeError: win is null 18:24:50 INFO - PROCESS | 1679 | Full stack: onSuccess@resource://app/components/nsBrowserGlue.js:758:17 18:24:50 INFO - PROCESS | 1679 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 18:24:50 INFO - PROCESS | 1679 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 18:24:50 INFO - PROCESS | 1679 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 18:24:50 INFO - PROCESS | 1679 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 18:24:50 INFO - PROCESS | 1679 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 18:24:50 INFO - PROCESS | 1679 | get _worker/worker.onmessage@resource://gre/modules/PromiseWorker.jsm:235:9 18:24:50 INFO - PROCESS | 1679 | EventHandlerNonNull*get _worker@resource://gre/modules/PromiseWorker.jsm:217:5 18:24:50 INFO - PROCESS | 1679 | postMessage@resource://gre/modules/PromiseWorker.jsm:291:9 18:24:50 INFO - PROCESS | 1679 | TaskImpl_run@resource://gre/modules/Task.jsm:319:40 18:24:50 INFO - PROCESS | 1679 | Handler.prototype.process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:937:23 18:24:50 INFO - PROCESS | 1679 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 18:24:50 INFO - PROCESS | 1679 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 18:24:50 INFO - PROCESS | 1679 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 18:24:50 INFO - PROCESS | 1679 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 18:24:50 INFO - PROCESS | 1679 | onError@resource://gre/modules/osfile/osfile_native.jsm:66:7 18:24:50 INFO - PROCESS | 1679 | 18:24:50 INFO - PROCESS | 1679 | ************************* 18:24:50 INFO - TEST-INFO | 1679: exit 0 18:24:50 INFO - Browser initialized. 18:24:50 INFO - Running cycle 1/1 for kraken test... 18:24:50 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpRMG33J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:25:02 INFO - PROCESS | 1680 | RSS: Main: 248590336 18:25:02 INFO - PROCESS | 1680 | 18:25:05 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 18:25:06 INFO - PROCESS | 1680 | RSS: Main: 303919104 18:25:06 INFO - PROCESS | 1680 | 18:25:08 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 18:25:09 INFO - PROCESS | 1680 | RSS: Main: 311992320 18:25:09 INFO - PROCESS | 1680 | 18:25:13 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 18:25:13 INFO - PROCESS | 1680 | RSS: Main: 404996096 18:25:13 INFO - PROCESS | 1680 | 18:25:15 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 18:25:16 INFO - PROCESS | 1680 | RSS: Main: 306180096 18:25:16 INFO - PROCESS | 1680 | 18:25:18 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:25:18 INFO - PROCESS | 1680 | RSS: Main: 310198272 18:25:18 INFO - PROCESS | 1680 | 18:25:21 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 18:25:22 INFO - PROCESS | 1680 | RSS: Main: 346382336 18:25:22 INFO - PROCESS | 1680 | 18:25:25 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 18:25:25 INFO - PROCESS | 1680 | RSS: Main: 348565504 18:25:25 INFO - PROCESS | 1680 | 18:25:28 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 18:25:29 INFO - PROCESS | 1680 | RSS: Main: 348655616 18:25:29 INFO - PROCESS | 1680 | 18:25:30 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:25:31 INFO - PROCESS | 1680 | RSS: Main: 316448768 18:25:31 INFO - PROCESS | 1680 | 18:25:33 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:25:34 INFO - PROCESS | 1680 | RSS: Main: 323321856 18:25:34 INFO - PROCESS | 1680 | 18:25:36 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:25:37 INFO - PROCESS | 1680 | RSS: Main: 314753024 18:25:37 INFO - PROCESS | 1680 | 18:25:39 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:25:40 INFO - PROCESS | 1680 | RSS: Main: 326107136 18:25:40 INFO - PROCESS | 1680 | 18:25:43 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:25:44 INFO - PROCESS | 1680 | RSS: Main: 328552448 18:25:44 INFO - PROCESS | 1680 | 18:25:45 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 18:25:46 INFO - PROCESS | 1680 | RSS: Main: 371961856 18:25:46 INFO - PROCESS | 1680 | 18:25:46 INFO - PROCESS | 1680 | __start_tp_report 18:25:46 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 18:25:46 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 18:25:46 INFO - PROCESS | 1680 | |i|pagename|runs| 18:25:46 INFO - PROCESS | 1680 | |0;ai-astar;102;115;115;114;107;112;113;113;114;113 18:25:46 INFO - PROCESS | 1680 | |1;audio-beat-detection;144;109;110;194;130;123;131;106;140;106 18:25:46 INFO - PROCESS | 1680 | |2;audio-dft;177;184;185;184;183;181;179;185;184;181 18:25:46 INFO - PROCESS | 1680 | |3;audio-fft;79;57;68;87;60;57;67;63;84;57 18:25:46 INFO - PROCESS | 1680 | |4;audio-oscillator;83;68;102;70;70;68;95;72;68;102 18:25:46 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;89;86;90;87;88;87;90;87;131;86 18:25:46 INFO - PROCESS | 1680 | |6;imaging-darkroom;95;95;95;96;95;95;94;96;103;144 18:25:46 INFO - PROCESS | 1680 | |7;imaging-desaturate;85;85;86;86;84;83;86;84;87;85 18:25:46 INFO - PROCESS | 1680 | |8;json-parse-financial;54;52;56;52;53;55;54;53;54;53 18:25:46 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;64;70;70;71;75;72;67;67;72;69 18:25:46 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;86;85;85;84;99;99;101;82;86;85 18:25:46 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;139;139;137;137;139;136;141;137;222;143 18:25:46 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;184;172;168;168;169;167;168;165;219;217 18:25:46 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;68;68;67;68;65;66;67;66;67;67 18:25:46 INFO - PROCESS | 1680 | __end_tp_report 18:25:46 INFO - PROCESS | 1680 | __start_cc_report 18:25:46 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,2056 18:25:46 INFO - PROCESS | 1680 | __end_cc_report 18:25:46 INFO - PROCESS | 1680 | __startTimestamp1471310746192__endTimestamp 18:25:46 INFO - PROCESS | 1680 | 18:25:46 INFO - PROCESS | 1680 | ------- Summary: start ------- 18:25:46 INFO - PROCESS | 1680 | Number of tests: 14 18:25:46 INFO - PROCESS | 1680 | 18:25:46 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.50 stddev:4.13 (3.6%) stddev-sans-first:2.42 18:25:46 INFO - PROCESS | 1680 | Values: 102.0 115.0 115.0 114.0 107.0 112.0 113.0 113.0 114.0 113.0 18:25:46 INFO - PROCESS | 1680 | 18:25:46 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:129.30 Median:130.50 stddev:26.73 (20.5%) stddev-sans-first:27.82 18:25:46 INFO - PROCESS | 1680 | Values: 144.0 109.0 110.0 194.0 130.0 123.0 131.0 106.0 140.0 106.0 18:25:46 INFO - PROCESS | 1680 | 18:25:46 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:182.30 Median:184.00 stddev:2.71 (1.5%) stddev-sans-first:2.09 18:25:46 INFO - PROCESS | 1680 | Values: 177.0 184.0 185.0 184.0 183.0 181.0 179.0 185.0 184.0 181.0 18:25:46 INFO - PROCESS | 1680 | 18:25:46 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:67.90 Median:67.50 stddev:11.50 (17.0%) stddev-sans-first:11.48 18:25:46 INFO - PROCESS | 1680 | Values: 79.0 57.0 68.0 87.0 60.0 57.0 67.0 63.0 84.0 57.0 18:25:46 INFO - PROCESS | 1680 | 18:25:46 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:79.80 Median:70.00 stddev:14.52 (20.7%) stddev-sans-first:15.36 18:25:46 INFO - PROCESS | 1680 | Values: 83.0 68.0 102.0 70.0 70.0 68.0 95.0 72.0 68.0 102.0 18:25:46 INFO - PROCESS | 1680 | 18:25:46 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:92.10 Median:87.50 stddev:13.75 (15.7%) stddev-sans-first:14.54 18:25:46 INFO - PROCESS | 1680 | Values: 89.0 86.0 90.0 87.0 88.0 87.0 90.0 87.0 131.0 86.0 18:25:46 INFO - PROCESS | 1680 | 18:25:46 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:100.80 Median:95.00 stddev:15.39 (16.2%) stddev-sans-first:16.18 18:25:46 INFO - PROCESS | 1680 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 94.0 96.0 103.0 144.0 18:25:46 INFO - PROCESS | 1680 | 18:25:46 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:1.20 (1.4%) stddev-sans-first:1.27 18:25:46 INFO - PROCESS | 1680 | Values: 85.0 85.0 86.0 86.0 84.0 83.0 86.0 84.0 87.0 85.0 18:25:46 INFO - PROCESS | 1680 | 18:25:46 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:1.26 (2.3%) stddev-sans-first:1.33 18:25:46 INFO - PROCESS | 1680 | Values: 54.0 52.0 56.0 52.0 53.0 55.0 54.0 53.0 54.0 53.0 18:25:46 INFO - PROCESS | 1680 | 18:25:46 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:70.50 stddev:3.13 (4.4%) stddev-sans-first:2.55 18:25:46 INFO - PROCESS | 1680 | Values: 64.0 70.0 70.0 71.0 75.0 72.0 67.0 67.0 72.0 69.0 18:25:46 INFO - PROCESS | 1680 | 18:25:46 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:89.20 Median:85.50 stddev:7.33 (8.6%) stddev-sans-first:7.68 18:25:46 INFO - PROCESS | 1680 | Values: 86.0 85.0 85.0 84.0 99.0 99.0 101.0 82.0 86.0 85.0 18:25:46 INFO - PROCESS | 1680 | 18:25:46 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:139.00 stddev:26.44 (19.0%) stddev-sans-first:27.88 18:25:46 INFO - PROCESS | 1680 | Values: 139.0 139.0 137.0 137.0 139.0 136.0 141.0 137.0 222.0 143.0 18:25:46 INFO - PROCESS | 1680 | 18:25:46 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.70 Median:170.50 stddev:20.86 (12.2%) stddev-sans-first:22.07 18:25:46 INFO - PROCESS | 1680 | Values: 184.0 172.0 168.0 168.0 169.0 167.0 168.0 165.0 219.0 217.0 18:25:46 INFO - PROCESS | 1680 | 18:25:46 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:0.99 (1.5%) stddev-sans-first:0.97 18:25:46 INFO - PROCESS | 1680 | Values: 68.0 68.0 67.0 68.0 65.0 66.0 67.0 66.0 67.0 67.0 18:25:46 INFO - PROCESS | 1680 | -------- Summary: end -------- 18:25:46 INFO - PROCESS | 1680 | 18:25:47 INFO - TEST-INFO | 1680: exit 0 18:25:47 INFO - TEST-OK | kraken | took 59427ms 18:25:47 INFO - SUITE-END | took 1177s 18:25:47 INFO - Completed test suite (00:19:37) 18:25:47 INFO - javascript benchmark 18:25:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3398.541196938413, 3406.829658517075, 3416.624171341152, 3438.071720635427, 3440.088376580207, 5126.41046279678, 5490.560755139584, 5680.176374425706, 5799.0, 5849.034909239976, 5780.335261444935, 5836.037053886114, 5933.110033494978, 5946.494547963417, 5953.642781433117, 5220.112580861243, 5229.8169564065265, 5288.814891478799, 5304.522592966632, 5341.519263266306, 5384.703841288728, 5388.353397592295, 5398.082326004569, 5399.0, 5399.244105825188, 5166.535011848914, 5246.658967167122, 5252.264682944391, 5304.840854774363, 5309.62832601718, 5286.894262114774, 5293.914747476758, 5300.575953923677, 5304.73476326186, 5307.150855863082, 4856.125897338477, 5054.812119151997, 5218.243354713583, 5275.973620131913, 5287.048331300364, 5745.942540574624, 5820.708964551798, 5861.677607731573, 5888.411158884121, 5894.469497745224, 6292.307846136921, 6352.0, 6352.364763523657, 6357.364263573651, 6367.108604795332, 6238.688065596702, 6257.405546473078, 6295.653739044351, 6316.2736285326855, 6323.810285691437, 5872.06047032477, 5943.167956486095, 5963.403659634002, 5969.04495280753, 5981.850453738604, 6342.556021078481, 6353.714082866282, 6354.9682251589375, 6361.586496877688, 6381.425671689477, 6107.8473038173515, 6143.389059649631, 6160.599561028519, 6263.467605253604, 6282.465990390868, 6333.714982825784, 6336.303006669263, 6348.523860710465, 6349.301576826545, 6385.553011289165, 6330.0821380899515, 6330.873382532323, 6333.429991300713, 6347.206599175103, 6353.777617783356, 6140.87237156979, 6233.470155036873, 6298.0552917062805, 6344.333844946306, 6354.618722876642, 5014.623903207201, 5040.092783298968, 5059.342285502935, 5072.442031376546, 5102.668326558762, 4535.297028960516, 4554.544545545419, 4555.886102847389, 4560.338750881104, 4588.908221835544, 5831.591788574759, 5877.588568800143, 5877.735501902424, 5882.294124705063, 5918.023526118229, 5448.774025844154, 6117.248930028882, 6251.031090181029, 6266.24805023391, 6293.496520278367, 5868.325142608569, 5882.55880808941, 5907.2911250650195, 5922.378150294243, 5925.881482370329, 4721.480637129913, 5457.20870473787, 5517.97917385285, 5531.612787104864, 5534.418886016991, 6173.783917562864, 6259.874802504016, 6347.079673447417, 6347.206599175103, 6367.554271200971, 5343.9198412024625, 6250.156228909221, 6262.780802671885, 6267.9373206267355, 6278.215223097113, 6427.871442571216, 6430.7749228776765, 6434.967825160844, 6436.871262574816, 6442.226932768098, 6106.217989796568, 6136.907946380898, 6174.351693072163, 6193.752249910133, 6216.595921265103, 6147.44672979434, 6213.409726076015, 6261.68691565429, 6276.9372306276355, 6285.0, 5995.190649262294, 6203.937960620517, 6208.255009398901, 6218.347073557211, 6219.129321895029, 6045.546584006129, 6127.448529632355, 6191.380861913773, 6204.5036397089025, 6224.564280500322, 6015.518758499397, 6072.715524739821, 6081.222629011544, 6085.398626455588, 6110.961136606877, 6148.9692551536955, 6157.784477543443, 6172.259328880384, 6173.98129308659, 6182.443580077815, 5953.434423729739, 6057.42454466825, 6077.091620541051, 6079.05774604937, 6125.601835880653], "value": 5773.68230211218, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.5802935387506, 496.10205527994356, 505.4591587001909, 522.2166749875187, 524.9553787928025, 30733.69266307339, 39952.0, 41066.178676426454, 41068.99999999996, 43473.7826310868, 42203.57796422035, 42799.86401631801, 42820.35769463461, 42893.0, 42964.57035429645, 8770.210681038705, 9725.854112188304, 9939.850902236472, 9942.10521053105, 9945.254105942047, 8923.018467968519, 9020.54897255138, 9033.999999999967, 9043.773905652346, 11860.822087668692, 11994.040476761838, 12235.265884046985, 12247.571335003277, 12248.081393895449, 12265.325407102606, 12062.0, 12107.455164517618, 12123.636290911287, 12133.332666703327, 12144.99999999991, 11969.760604787856, 12054.0, 12063.638090857288, 12073.637790866289, 12090.153689241757, 40230.59769402327, 40516.0, 40523.797381012904, 40550.0, 40576.18847623031, 3631.4189729643394, 3699.7965111918834, 3715.294094122108, 4008.2785098682225, 4232.195882782262, 24328.148514802073, 31146.221344466343, 31621.683783162334, 32197.0, 32589.0, 3225.1130938991732, 3237.0, 3262.4616938205168, 3265.3959017581824, 3348.430766769655, 493629.0, 497694.0, 500175.0, 501815.98184018425, 502436.99999999633, 59753.701231493564, 60047.69976150179, 60293.69853150706, 60370.39629603648, 60373.39626603766, 34615.0, 34879.12802179915, 35061.47407788885, 36368.0, 63046.42383940438, 16279.430219942245, 16454.83545164533, 16457.506274811774, 16510.091944942902, 16530.421435249707, 12835.93582032084, 12932.67668308281, 12949.676258093623, 12953.417096230693, 12955.287459189647, 16523.752143717855, 16818.24317905697, 16836.24236909342, 17304.913475432793, 18775.96732179743, 6536.771213007617, 7822.960885195537, 7888.2506161914525, 7943.960280198562, 7964.601769911481, 822.3955392786297, 937.2642475656577, 948.170350942925, 1029.8197815382277, 1048.2033654422694, 23313.88343058308, 29973.700262997092, 29990.40019199604, 30748.077557673307, 30755.15652026108, 1001.67946257197, 1073.7959787640323, 1074.8441475985937, 1110.7945030169444, 1220.8901198892142, 15057.397704091714, 15550.688986220212, 15568.299426525833, 15607.531774046796, 15940.760888586681, 673441.2655873477, 678136.0, 678600.0, 679485.0, 681280.0, 51658.0, 57857.710711446176, 58467.83064338689, 59061.5234619138, 59085.40914590885, 1282.8011658192995, 1297.7080156964796, 1323.9669008274677, 1327.8207441995207, 1333.4266265505732, 30310.0, 30795.230119247197, 31401.05796826099, 31554.0, 32666.83666581747, 51711.48288517217, 52248.0, 52332.9533409322, 52343.47656523538, 52371.21443178279, 439298.0, 450393.0, 450546.0, 450689.0, 451383.0, 41090.1781964365, 41772.79113604413, 41970.58029419789, 41996.37005444982, 42276.0, 40744.77765667035, 45078.87302817456, 46478.83802904955, 46981.0, 47063.529364705915, 33042.17394565059, 37903.62096379001, 39091.804540977115, 39139.80430097945, 39824.80087599543, 34507.65492345044, 38203.0, 38866.61133388743, 39055.24300478417, 39384.2123157541, 37522.624773751915, 37993.62006379901, 38115.237695245385, 38201.23597528089, 38393.0, 32721.018369448953, 33567.244447109944, 33983.320333593685, 34289.48565771466, 34432.82783586066, 24976.37559061038, 25089.87455062713, 25304.367390815376, 25485.984801295628, 25728.228153155436], "value": 20294.96527845036, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.66129845997074, 369.52700543304564, 373.2423180942686, 380.2926556604715, 400.8757285241575, 15335.69328613427, 16255.34978600856, 20298.391048268575, 20875.37373878782, 20964.89517552414, 18042.278308867662, 18415.539611509685, 18431.262749490004, 19147.234110635593, 21337.25319613814, 5625.662460252398, 5868.73590688419, 6132.233470816148, 6133.447989680928, 6143.477804386633, 6609.966950165242, 6763.222229443628, 6797.0, 6805.965970170142, 6833.6241506717115, 6064.393560643923, 6457.128287681174, 6699.464042876558, 6712.899306510381, 6725.394714475696, 6408.647524386157, 6655.203095164303, 6698.464122870208, 6698.665066746643, 6720.59676419414, 5702.0021496238, 5779.855503612402, 5781.0, 5790.7104644768015, 5812.88374232513, 25488.362790930376, 26166.084187053362, 26178.476430471474, 26434.6034809478, 26849.0, 2333.9533209335887, 2360.586897292984, 2542.9618555721677, 2589.6374507568958, 3015.9849200754074, 9708.320417570772, 10194.388336699822, 10293.53679084443, 10398.0, 10421.583136674524, 1811.7372980917826, 1882.5670095877888, 1886.6226754648987, 1919.2418994497234, 1934.303650685752, 90062.0, 92218.0, 97404.51297743537, 124927.75072249252, 128815.35592321979, 9680.322377433653, 10805.297655652515, 11271.041961433206, 11288.435578221057, 11491.42542872853, 14578.271086445635, 14582.19797911125, 14592.343344549521, 14612.48856290046, 14624.85375146235, 7437.256274372519, 8352.958235208907, 8507.276881465144, 8652.307815374756, 8759.211670949646, 7086.610236437045, 8515.403921725543, 8548.487090774574, 8711.60797764102, 8721.389502734748, 4466.935246143277, 4517.628245171311, 4551.112533056023, 4608.400907882021, 4668.603168730628, 1755.1399814091003, 1859.3399343232957, 1901.543629528903, 1903.8191371819655, 1909.3699079303797, 733.7615275035635, 870.3428911172151, 903.8101998580372, 961.0965692249264, 1054.541274545575, 20963.79036209649, 21673.783262167493, 21702.0, 21714.56570868574, 21745.02147403339, 865.4677373415304, 906.9909300906907, 933.6358820060182, 949.2121539122512, 985.8164166371522, 10907.29102608328, 11319.151063670257, 11347.148963827745, 11437.828432573519, 11612.477438515287, 497979.0, 507452.0, 582866.0, 606663.0, 606687.0, 125593.74406256003, 126710.0, 132297.0, 132565.0, 132646.0, 979.9069088436587, 989.8465737810651, 1018.0, 1035.1046344911597, 1035.9430231337199, 18489.260429582668, 19296.4211073668, 19317.227310907405, 19338.323158689378, 21361.78638213598, 38629.22741545097, 49717.0, 54554.63613409538, 55514.889702206536, 56742.0, 68153.65923170352, 74519.62740186264, 79087.60456197683, 80205.0, 81250.59374703326, 5430.592705547178, 5553.916691249715, 7610.505317154367, 7932.36541076724, 8451.45065570736, 10992.945035275092, 12827.743445131231, 12838.743225135631, 13281.269530175932, 13305.268210248529, 11304.434778261219, 11310.886891130984, 11466.656000320003, 11536.59621913229, 11791.757266751712, 10906.890931090587, 11153.219274650697, 11207.887921120684, 11575.652730418102, 11654.0, 10999.120070394189, 11208.607698730506, 11388.316700997966, 11457.0, 11754.70613234676, 7718.382529397522, 7826.699472201341, 8187.426880118573, 8402.705905293285, 8491.83016339682, 8209.220124088202, 10425.478726063819, 11043.171762118034, 11821.172517924007, 11821.881781182423], "value": 9602.873242821555, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1457.934392952317, 1539.7921280627113, 1741.120734029315, 1891.442024602742, 1907.227572833003, 8366.91633083669, 8902.0, 8908.955455222724, 8912.554372281376, 8931.821363572724, 6897.5861448313135, 7872.1734217907015, 8099.757007289776, 8168.224018718212, 8730.650773969033, 2173.206779525474, 2206.6248737714545, 2277.1005452846116, 2359.7994170495444, 2366.5740166769974, 2356.0, 2356.964645530318, 2363.763623637631, 2367.0886708617136, 2370.8577485350934, 2320.3155069254526, 2377.0967032527615, 2377.3699969508093, 2379.4646204604, 2385.451346190377, 2328.092044102801, 2341.976580234193, 2360.9173678921156, 2370.573296806574, 2372.6203807390734, 2326.4881726020417, 2340.0756701103137, 2344.4373350395954, 2369.869657168855, 2413.481101563155, 8530.317574594017, 8620.482771033758, 8896.59965301563, 8985.505797181153, 9003.189712925869, 5231.973840130813, 5394.3796463406425, 5398.838034858961, 5406.783728650849, 5406.91889621656, 3485.686288234071, 3607.801570913599, 3628.6371362863765, 3656.6891814195824, 3693.2059607184315, 1059.1050562274866, 1089.2593036735018, 1111.4553868604326, 1116.972075698106, 1119.832025196227, 11728.296302221894, 11742.647720568311, 11751.294922304689, 11768.176227664067, 11782.410879455992, 4684.1334353144775, 4823.734694591761, 4857.878553036202, 4900.529841047672, 5000.924986125211, 9894.406335619886, 10203.94898025505, 10224.028717271847, 10247.128994035442, 10270.33242839228, 5423.322084739408, 5479.0959491684225, 5482.0, 5483.232347471358, 5492.890142197134, 4873.41519017713, 4916.680415772963, 4945.7527123644395, 4989.825356112591, 4999.0501804657, 3689.4281386385155, 3805.4291856221234, 3971.1065010372436, 4142.79286035697, 4144.0, 1146.7901364060995, 1153.1985270237105, 1274.6494713953552, 1292.3279894454831, 1310.4365122997203, 774.9070111586645, 841.3563623827777, 872.1147288828381, 925.6390007896791, 947.1097168661436, 5879.470847623735, 5902.61632993854, 6018.488428483628, 6112.113743507167, 6145.784897528565, 929.5816882402946, 980.1129977370491, 997.8952210017989, 1023.2785885950387, 1041.906228439444, 3578.964210357863, 3899.1811719539214, 3944.9211015780097, 4010.2581022510344, 4118.299889018874, 10528.526216320284, 10822.837657435144, 10828.971247731453, 10830.566777328977, 10849.403282819521, 10275.7431064224, 10770.407627580402, 10773.976472235125, 10777.83833242502, 10782.029617334478, 7964.482308649919, 8187.754367368989, 8192.098869124391, 8193.426460147732, 8208.179182081863, 5718.485336319584, 5879.764809407576, 5891.1457838614015, 5955.910661339996, 6095.420935011167, 8564.871526926976, 9619.855702164334, 9894.713687220616, 10000.649977250761, 10139.7465063374, 9686.321957463191, 9987.60049598037, 10124.59501619927, 10300.175985920958, 10301.536430860479, 7507.211742766931, 7587.317141457295, 7674.079110506552, 7678.347340475899, 7703.114141873756, 8197.71308004217, 8200.302974247255, 8267.049289331564, 8298.460600060977, 8307.086220515617, 7397.704091836266, 7511.3615342696485, 7574.886376704464, 7594.126675432396, 7701.383889288732, 7407.148177959388, 7416.332530072319, 7428.442866785121, 7509.098908131059, 7524.736634217776, 6858.554193977376, 7023.894641580278, 7074.469414793808, 7077.0, 7082.3979961703835, 4946.060248552763, 4956.653034287565, 5036.748162591783, 5050.595952323879, 5107.6169287302855, 4858.441279252931, 4963.404391473046, 5026.899462010812, 5059.3169922061525, 5247.921281180709], "value": 4619.706320034885, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.8108289070658, 301.8551095474172, 308.27401469539234, 312.30044699872263, 314.8787716729059, 4507.504174540798, 5485.341758988927, 5620.381758006621, 5684.573656975723, 5705.25831641887, 5485.917711234335, 5588.86027849304, 5589.189567512708, 5591.2731344925205, 5605.859853503664, 3614.8011859347725, 3843.5586655004445, 4183.247015537202, 4242.387892600807, 4327.545607711192, 4316.007318316773, 4320.870373888788, 4332.003639162995, 4337.262665346899, 4341.348797680357, 3254.4630136027527, 4174.603412675791, 4187.560306167854, 4201.516825565069, 4222.9155416891645, 4206.137741762931, 4226.387173859804, 4227.8584782108655, 4232.397075798377, 4237.4279472271155, 3400.9934138858193, 3406.9268180523304, 3634.327649384872, 3650.4889315495584, 3677.7425580209397, 4551.886202844922, 5017.498250174954, 5340.305760251182, 5548.889022219533, 5556.277683901067, 4737.976310118427, 4742.407199100113, 4757.833475828364, 4765.571098601108, 4777.355057067286, 5122.0, 5362.973185134049, 5711.457411545896, 5832.966857234257, 5880.970595146997, 3212.4378233809225, 3667.431548110047, 3878.1661942682176, 3899.5320561532512, 3920.882373528799, 5890.145928840294, 6015.3383127855905, 6017.368176341465, 6037.5849660135855, 6091.329953705133, 3856.1902000580194, 3999.280489267326, 4001.579834117434, 4022.738521996061, 4071.531773846046, 3488.8081155536183, 3712.2946640138825, 3875.4961854958674, 3898.3762597984187, 4125.628693417607, 4671.672982891233, 4686.062787442525, 4711.293306004127, 4725.9527404726205, 4831.275308703723, 5761.9135712964335, 5767.826965191051, 5770.278715160605, 5851.414858514199, 5871.324797648325, 1197.4012993503247, 1301.4078594239597, 1363.7681594129022, 1388.2434073430004, 1397.182648150843, 825.6335764310077, 830.8368284401886, 837.5560952695081, 843.4601854812925, 856.4219152072326, 3980.208905992315, 4505.662075344362, 4518.593326600621, 4526.0537968673125, 4616.630669546428, 4974.477679843564, 5040.97541475112, 5054.321209758834, 5129.358830146231, 5139.177731562932, 3964.147708242684, 4630.53694630541, 5026.396832380065, 5501.752421141058, 5578.888422231614, 2698.5007773561956, 3154.558361829346, 3155.605549306337, 3175.3490534440384, 3177.3327601203546, 5403.648762830403, 5499.642523235977, 5792.594518383757, 6250.281217660027, 6282.434580887742, 5955.064604003716, 6096.0, 6104.870598939122, 6172.969135154295, 6227.875442491125, 5696.401877802854, 6184.690765461709, 6197.597156184924, 6318.304986451487, 6333.398327158912, 5423.535645375648, 5544.695041772741, 5962.850928726816, 5978.521718262616, 6085.391460853879, 5573.627955087276, 5893.852653683693, 5901.200133959183, 5911.970440147772, 5916.49709774674, 4625.028743963805, 6027.397260273938, 6062.696865156813, 6203.627782333075, 6243.937560624518, 3640.872569460056, 3925.175713100281, 3944.802759861938, 4050.23045621331, 4064.6992962251798, 5812.244408226903, 5847.649141051551, 5902.20320256777, 6016.21815001772, 6090.746423823214, 5001.799928002839, 5605.346422805201, 5829.737661805314, 5862.736176871966, 5884.205632239593, 5345.679259244458, 5611.0, 5803.332616748959, 5817.563682723898, 5843.707814609167, 5047.671901326402, 5494.890102197854, 5622.606417550732, 5701.230333905036, 5737.34020587621, 5370.382406023357, 5599.832005039855, 5759.787392409876, 5826.912596311144, 5869.4717475427415, 5401.540869026019, 5713.942860571341, 5749.137629355463, 5766.855828604318, 5793.420657934173], "value": 4200.55922876396, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.6548473990114, 746.7535713214639, 769.3076231391747, 780.7579650308405, 865.8225063861908, 15378.692426151485, 16296.185190740445, 16301.429449969253, 16324.428644997413, 16341.83658163418, 12637.49450021998, 14756.6310842229, 14894.851051489482, 14929.477468288613, 14962.32669529871, 670.9194896612413, 707.1937990690617, 829.887965124708, 861.3841103610916, 863.848826455371, 855.2943821347383, 857.7641148684099, 859.0893652728097, 859.5315553023618, 859.9699010534632, 913.4108500017485, 914.7027216154777, 915.6428992692822, 916.5829547555846, 918.4581097152678, 706.7561691216522, 731.349099301622, 733.8495608400265, 734.9706011759523, 912.3020889019905, 540.824232124561, 649.8407890066917, 668.2014992084461, 675.0852594734114, 677.7898851356096, 14572.0, 14594.635134121625, 14595.124292542376, 14596.781048284285, 14631.341589628386, 11042.840501747361, 12666.11337206396, 12696.936515317364, 12993.415296311689, 13245.668858278525, 12033.682316783672, 12355.567555135616, 12458.501659933592, 12549.435275412628, 12960.80558791619, 4504.086227640556, 4508.571685689854, 4555.886102847422, 4609.055143695535, 4715.929261061086, 103876.0, 104750.47624761828, 106128.46935765349, 107170.46414767877, 108011.0, 10740.838887416696, 10775.892241077489, 10810.783784324269, 10825.566977320976, 11165.609203677832, 20965.22625927317, 20978.265760698607, 21000.579988400146, 21016.789832101484, 21019.47451313699, 6999.950007498814, 7556.622168891534, 7656.0, 7925.167857374892, 7986.520808751494, 4315.201687687788, 4326.783660816946, 4462.955370446254, 4513.255312873404, 4581.748003859753, 2960.3931194105157, 3504.281622267429, 3508.1580420698847, 3511.3152935177905, 3511.8244087795506, 641.9358064193636, 652.2043107408954, 659.970301336441, 660.5970358081566, 661.0810972747885, 517.3998162131909, 518.225253246399, 591.6213623281103, 598.3238939997775, 600.5075837813039, 7256.2743725627015, 8111.797205069803, 8177.059638141571, 8534.975802903691, 9288.72133835986, 588.5096712086751, 637.2162240444187, 639.2744235292884, 645.5803727577019, 646.4537465841356, 4430.623397011225, 4475.6419486441655, 4540.121308541327, 4548.0, 4617.238155704271, 103228.0, 129248.0, 164873.17563412347, 169164.0, 173230.0, 107686.46156769323, 111004.4449777762, 142255.2887235557, 144146.0, 144215.0, 105036.0, 119926.0, 123392.38303808424, 124514.52810391255, 125995.8501037463, 8325.167483251626, 8534.359923005675, 8541.444806087584, 8910.955445222991, 8945.284377249674, 65968.0, 67484.0, 67533.31166720872, 68234.31765682479, 68380.31619683938, 77184.0, 82872.58563707385, 83605.58197208976, 84459.73310400225, 103305.0, 11065.424659719041, 11864.76270474603, 13325.666858328232, 13385.732285354434, 13787.241701706502, 12798.232106073665, 12945.223286602833, 12974.935125324313, 13049.934750326187, 13544.458221671417, 13208.141470804367, 13591.252481113634, 13639.79540306916, 14260.857391425952, 14927.552173434815, 14249.715005700034, 14286.642833929234, 14416.855831441551, 14465.927670361581, 14619.269036548343, 13716.314184290546, 13757.10578812374, 13862.168269903435, 13868.791968120688, 13978.580642580739, 10886.0, 11609.593664221711, 11745.941270293593, 11829.231099978473, 12117.15179937396, 11526.769464610828, 11671.07962702614, 11723.706907327385, 11725.941370293094, 11943.402829858645], "value": 7747.5049328310015, "unit": "score"}], "name": "dromaeo_css", "value": 7436.954196225232, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 115.0, 114.0, 107.0, 112.0, 113.0, 113.0, 114.0, 113.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 109.0, 110.0, 194.0, 130.0, 123.0, 131.0, 106.0, 140.0, 106.0], "value": 129.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 184.0, 185.0, 184.0, 183.0, 181.0, 179.0, 185.0, 184.0, 181.0], "value": 182.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 68.0, 87.0, 60.0, 57.0, 67.0, 63.0, 84.0, 57.0], "value": 67.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 68.0, 102.0, 70.0, 70.0, 68.0, 95.0, 72.0, 68.0, 102.0], "value": 79.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 87.0, 88.0, 87.0, 90.0, 87.0, 131.0, 86.0], "value": 92.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 94.0, 96.0, 103.0, 144.0], "value": 100.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 86.0, 84.0, 83.0, 86.0, 84.0, 87.0, 85.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 56.0, 52.0, 53.0, 55.0, 54.0, 53.0, 54.0, 53.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 70.0, 70.0, 71.0, 75.0, 72.0, 67.0, 67.0, 72.0, 69.0], "value": 69.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 85.0, 84.0, 99.0, 99.0, 101.0, 82.0, 86.0, 85.0], "value": 89.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 137.0, 137.0, 139.0, 136.0, 141.0, 137.0, 222.0, 143.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 172.0, 168.0, 168.0, 169.0, 167.0, 168.0, 165.0, 219.0, 217.0], "value": 179.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 68.0, 65.0, 66.0, 67.0, 66.0, 67.0, 67.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1455.2000000000003, "alertThreshold": 2.0}]} 18:25:47 INFO - Return code: 0 18:25:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:25:47 ERROR - # TBPL SUCCESS # 18:25:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:25:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:25:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:25:47 INFO - 2016-08-15 18:25:47.499 screenresolution[1684:10321] starting screenresolution argv=screenresolution get 18:25:47 INFO - 2016-08-15 18:25:47.501 screenresolution[1684:10321] Display 0: 1600x1200x32@60 18:25:47 INFO - 2016-08-15 18:25:47.507 screenresolution[1685:10324] starting screenresolution argv=screenresolution list 18:25:47 INFO - Available Modes on Display 0 18:25:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:25:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:25:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:25:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:25:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:25:47 INFO - Intel Iris: 18:25:47 INFO - Chipset Model: Intel Iris 18:25:47 INFO - Type: GPU 18:25:47 INFO - Bus: Built-In 18:25:47 INFO - VRAM (Dynamic, Max): 1536 MB 18:25:47 INFO - Vendor: Intel (0x8086) 18:25:47 INFO - Device ID: 0x0a2e 18:25:47 INFO - Revision ID: 0x0009 18:25:47 INFO - Displays: 18:25:47 INFO - Display: 18:25:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:25:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:25:47 INFO - Display Serial Number: 1600x1200 60 18:25:47 INFO - Main Display: Yes 18:25:47 INFO - Mirror: Off 18:25:47 INFO - Online: Yes 18:25:47 INFO - Rotation: Supported 18:25:47 INFO - Return code: 0 18:25:47 INFO - Running post-action listener: _resource_record_post_action 18:25:47 INFO - [mozharness: 2016-08-16 01:25:47.749425Z] Finished run-tests step (success) 18:25:47 INFO - Running post-run listener: _resource_record_post_run 18:25:47 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 31468544; Write bytes: 705021440; Read time: 197; Write time: 1942 18:25:47 INFO - TinderboxPrint: CPU usage
53.7% 18:25:47 INFO - TinderboxPrint: I/O read bytes / time
31,468,544 / 197 18:25:47 INFO - TinderboxPrint: I/O write bytes / time
705,021,440 / 1,942 18:25:47 INFO - TinderboxPrint: CPU idle
2,246.6 (46.3%) 18:25:47 INFO - TinderboxPrint: CPU system
971.6 (20.0%) 18:25:47 INFO - TinderboxPrint: CPU user
1,635.5 (33.7%) 18:25:47 INFO - TinderboxPrint: Swap in / out
446,816,256 / 0 18:25:47 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 258048; Write bytes: 407758848; Read time: 7; Write time: 824 18:25:47 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 28699648; Write bytes: 295693824; Read time: 171; Write time: 1111 18:25:48 INFO - Running post-run listener: _upload_blobber_files 18:25:48 INFO - Blob upload gear active. 18:25:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:25:48 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 18:25:48 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'] 18:25:48 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 18:25:48 INFO - (blobuploader) - INFO - Open directory for files ... 18:25:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:25:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:25:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:49 INFO - (blobuploader) - INFO - Done attempting. 18:25:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:25:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:25:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:50 INFO - (blobuploader) - INFO - Done attempting. 18:25:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:25:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:25:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:53 INFO - (blobuploader) - INFO - Done attempting. 18:25:53 INFO - (blobuploader) - INFO - Iteration through files over. 18:25:53 INFO - Return code: 0 18:25:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:25:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:25:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b48f3b42a15e26d4439ecb3d554d55ea460c2d1d9ac61f72b34aca063b35472f62e6235adfb5d72ecdfa70c73281cf46550acb3673fd03dc1e437c37c9eb56e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/02946a99dec02e3fdaaf470c6469f232292adaf7a9e6f858c34f1592ed3d49c1c56176b376d83a441e39a77198cafd29b98312e4705c889abcb7f21e0764895a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6ed8e75dcb3c942f1e95f0f39753fc7edae5bdab023acd3355ab42fceb12ea8419129f1d5afcbb8c651dc751681532fd9c034928dd5ad3607969a95b9ac23471"} 18:25:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:25:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:25:53 INFO - Contents: 18:25:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b48f3b42a15e26d4439ecb3d554d55ea460c2d1d9ac61f72b34aca063b35472f62e6235adfb5d72ecdfa70c73281cf46550acb3673fd03dc1e437c37c9eb56e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/02946a99dec02e3fdaaf470c6469f232292adaf7a9e6f858c34f1592ed3d49c1c56176b376d83a441e39a77198cafd29b98312e4705c889abcb7f21e0764895a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6ed8e75dcb3c942f1e95f0f39753fc7edae5bdab023acd3355ab42fceb12ea8419129f1d5afcbb8c651dc751681532fd9c034928dd5ad3607969a95b9ac23471"} 18:25:53 INFO - Running post-run listener: copy_logs_to_upload_dir 18:25:53 INFO - Copying logs to upload dir... 18:25:53 INFO - mkdir: /builds/slave/test/build/upload/logs 18:25:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.248110 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-15 18:25:53.211615) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 18:25:53.214979) ========= 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.HWPm30HWRz/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.hCsngWTAFF/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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/b48f3b42a15e26d4439ecb3d554d55ea460c2d1d9ac61f72b34aca063b35472f62e6235adfb5d72ecdfa70c73281cf46550acb3673fd03dc1e437c37c9eb56e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/02946a99dec02e3fdaaf470c6469f232292adaf7a9e6f858c34f1592ed3d49c1c56176b376d83a441e39a77198cafd29b98312e4705c889abcb7f21e0764895a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6ed8e75dcb3c942f1e95f0f39753fc7edae5bdab023acd3355ab42fceb12ea8419129f1d5afcbb8c651dc751681532fd9c034928dd5ad3607969a95b9ac23471"} build_url:https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010864 build_url: 'https://queue.taskcluster.net/v1/task/GQK2wVtsQEySu7dndY03lA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b48f3b42a15e26d4439ecb3d554d55ea460c2d1d9ac61f72b34aca063b35472f62e6235adfb5d72ecdfa70c73281cf46550acb3673fd03dc1e437c37c9eb56e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/02946a99dec02e3fdaaf470c6469f232292adaf7a9e6f858c34f1592ed3d49c1c56176b376d83a441e39a77198cafd29b98312e4705c889abcb7f21e0764895a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6ed8e75dcb3c942f1e95f0f39753fc7edae5bdab023acd3355ab42fceb12ea8419129f1d5afcbb8c651dc751681532fd9c034928dd5ad3607969a95b9ac23471"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-15 18:25:53.248176) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 18:25:53.248488) ========= 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.HWPm30HWRz/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.hCsngWTAFF/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.004652 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-15 18:25:53.297750) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 18:25:53.298043) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-15 18:25:53.298377) ========= ========= Total master_lag: 5.15 =========