builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0197 starttime: 1469153209.09 results: success (0) buildid: 20160721160514 builduid: a9be7b57be0345549312297387ef1a93 revision: 7ba94e0c5daa48d7667519c930eca31d98648512 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 19:06:49.086121) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 19:06:49.086606) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 19:06:49.086890) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 19:06:49.115642) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 19:06:49.116038) ========= 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.yH2KSPLcXy/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.9N1TXglk0O/Listeners TMPDIR=/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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-07-21 19:06:49-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.2M=0.001s 2016-07-21 19:06:49 (13.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.477836 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 19:06:49.616812) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 19:06:49.617169) ========= 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.yH2KSPLcXy/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.9N1TXglk0O/Listeners TMPDIR=/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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.027632 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 19:06:49.665946) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 19:06:49.666307) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 7ba94e0c5daa48d7667519c930eca31d98648512 --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 7ba94e0c5daa48d7667519c930eca31d98648512 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yH2KSPLcXy/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.9N1TXglk0O/Listeners TMPDIR=/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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-07-21 19:06:49,742 truncating revision to first 12 chars 2016-07-21 19:06:49,742 Setting DEBUG logging. 2016-07-21 19:06:49,742 attempt 1/10 2016-07-21 19:06:49,742 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/7ba94e0c5daa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 19:06:50,946 unpacking tar archive at: fx-team-7ba94e0c5daa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.472761 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 19:06:51.188610) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 19:06:51.188899) ========= script_repo_revision: 7ba94e0c5daa48d7667519c930eca31d98648512 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 19:06:51.189280) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 19:06:51.189583) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 19:06:51.228702) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-21 19:06:51.228972) ========= /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.yH2KSPLcXy/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.9N1TXglk0O/Listeners TMPDIR=/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 19:06:51 INFO - MultiFileLogger online at 20160721 19:06:51 in /builds/slave/test 19:06:51 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 19:06:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:06:51 INFO - {'append_to_log': False, 19:06:51 INFO - 'base_work_dir': '/builds/slave/test', 19:06:51 INFO - 'blob_upload_branch': 'Fx-Team', 19:06:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:06:51 INFO - 'branch': 'Fx-Team', 19:06:51 INFO - 'buildbot_json_path': 'buildprops.json', 19:06:51 INFO - 'config_files': ('talos/mac_config.py',), 19:06:51 INFO - 'default_actions': ('clobber', 19:06:51 INFO - 'read-buildbot-config', 19:06:51 INFO - 'download-and-extract', 19:06:51 INFO - 'populate-webroot', 19:06:51 INFO - 'create-virtualenv', 19:06:51 INFO - 'install', 19:06:51 INFO - 'run-tests'), 19:06:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:06:51 INFO - 'download_minidump_stackwalk': True, 19:06:51 INFO - 'download_symbols': 'ondemand', 19:06:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:06:51 INFO - 'tooltool.py': '/tools/tooltool.py', 19:06:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:06:51 INFO - '/tools/misc-python/virtualenv.py')}, 19:06:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:06:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:06:51 INFO - 'installer_path': 'installer.exe', 19:06:51 INFO - 'log_level': 'info', 19:06:51 INFO - 'log_name': 'talos', 19:06:51 INFO - 'log_to_console': True, 19:06:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:06:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:06:51 INFO - 'opt_config_files': (), 19:06:51 INFO - 'pip_index': False, 19:06:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:06:51 INFO - 'cmd': ('bash', 19:06:51 INFO - '-c', 19:06:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:06:51 INFO - 'enabled': True, 19:06:51 INFO - 'halt_on_failure': False, 19:06:51 INFO - 'name': 'check_screen_resolution'},), 19:06:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:06:51 INFO - 'cmd': ('bash', 19:06:51 INFO - '-c', 19:06:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:06:51 INFO - 'enabled': True, 19:06:51 INFO - 'halt_on_failure': False, 19:06:51 INFO - 'name': 'check_screen_resolution'},), 19:06:51 INFO - 'run_cmd_checks_enabled': True, 19:06:51 INFO - 'sps_profile': False, 19:06:51 INFO - 'sps_profile_interval': 0, 19:06:51 INFO - 'suite': 'dromaeojs', 19:06:51 INFO - 'system_bits': '32', 19:06:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:06:51 INFO - 'title': 't-yosemite-r7-0197', 19:06:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:06:51 INFO - 'use_talos_json': True, 19:06:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:06:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:06:51 INFO - 'work_dir': 'build'} 19:06:51 INFO - [mozharness: 2016-07-22 02:06:51.438948Z] Running clobber step. 19:06:51 INFO - Running pre-action listener: _resource_record_pre_action 19:06:51 INFO - Running main action method: clobber 19:06:51 INFO - rmtree: /builds/slave/test/build 19:06:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:06:53 INFO - Running post-action listener: _resource_record_post_action 19:06:53 INFO - [mozharness: 2016-07-22 02:06:53.608683Z] Finished clobber step (success) 19:06:53 INFO - [mozharness: 2016-07-22 02:06:53.608813Z] Running read-buildbot-config step. 19:06:53 INFO - Running pre-action listener: _resource_record_pre_action 19:06:53 INFO - Running main action method: read_buildbot_config 19:06:53 INFO - Using buildbot properties: 19:06:53 INFO - { 19:06:53 INFO - "project": "", 19:06:53 INFO - "product": "firefox", 19:06:53 INFO - "script_repo_revision": "production", 19:06:53 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 19:06:53 INFO - "repository": "", 19:06:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 19:06:53 INFO - "buildid": "20160721160514", 19:06:53 INFO - "pgo_build": "False", 19:06:53 INFO - "basedir": "/builds/slave/test", 19:06:53 INFO - "buildnumber": 114, 19:06:53 INFO - "slavename": "t-yosemite-r7-0197", 19:06:53 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 19:06:53 INFO - "platform": "yosemite_r7", 19:06:53 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 19:06:53 INFO - "branch": "fx-team", 19:06:53 INFO - "repo_path": "integration/fx-team", 19:06:53 INFO - "revision": "7ba94e0c5daa48d7667519c930eca31d98648512", 19:06:53 INFO - "stage_platform": "macosx64", 19:06:53 INFO - "builduid": "a9be7b57be0345549312297387ef1a93", 19:06:53 INFO - "slavebuilddir": "test" 19:06:53 INFO - } 19:06:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 19:06:53 INFO - Running post-action listener: _resource_record_post_action 19:06:53 INFO - [mozharness: 2016-07-22 02:06:53.612340Z] Finished read-buildbot-config step (success) 19:06:53 INFO - [mozharness: 2016-07-22 02:06:53.612423Z] Running download-and-extract step. 19:06:53 INFO - Running pre-action listener: _resource_record_pre_action 19:06:53 INFO - Running main action method: download_and_extract 19:06:53 INFO - mkdir: /builds/slave/test/build/tests 19:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:06:53 INFO - https://queue.taskcluster.net/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:06:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 19:06:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 19:06:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 19:06:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 19:06:54 INFO - Downloaded 1199 bytes. 19:06:54 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 19:06:54 INFO - Using the following test package requirements: 19:06:54 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 19:06:54 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:06:54 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 19:06:54 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:06:54 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 19:06:54 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:06:54 INFO - u'jsshell-mac.zip'], 19:06:54 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:06:54 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 19:06:54 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 19:06:54 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:06:54 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 19:06:54 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:06:54 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 19:06:54 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:06:54 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 19:06:54 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 19:06:54 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 19:06:54 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:06:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:06:54 INFO - https://queue.taskcluster.net/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:06:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:06:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:06:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:06:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:06:55 INFO - Downloaded 18806677 bytes. 19:06:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:06:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:06:59 INFO - Return code: 0 19:06:59 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:06:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:06:59 INFO - https://queue.taskcluster.net/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:06:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:06:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:06:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 19:06:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:07:00 INFO - Downloaded 18806677 bytes. 19:07:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:07:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:07:04 INFO - Return code: 0 19:07:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:04 INFO - https://queue.taskcluster.net/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:07:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 19:07:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 19:07:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 19:07:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:07:05 INFO - Downloaded 14403317 bytes. 19:07:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:07:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 19:07:05 INFO - Return code: 0 19:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:05 INFO - https://queue.taskcluster.net/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 19:07:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 19:07:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 19:07:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 19:07:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 19:07:09 INFO - Downloaded 112776566 bytes. 19:07:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 19:07:09 INFO - mkdir: /builds/slave/test/properties 19:07:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:07:09 INFO - Writing to file /builds/slave/test/properties/build_url 19:07:09 INFO - Contents: 19:07:09 INFO - build_url:https://queue.taskcluster.net/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 19:07:10 INFO - Running post-action listener: _resource_record_post_action 19:07:10 INFO - Running post-action listener: set_extra_try_arguments 19:07:10 INFO - [mozharness: 2016-07-22 02:07:10.190161Z] Finished download-and-extract step (success) 19:07:10 INFO - [mozharness: 2016-07-22 02:07:10.190329Z] Running populate-webroot step. 19:07:10 INFO - Running pre-action listener: _resource_record_pre_action 19:07:10 INFO - Running main action method: populate_webroot 19:07:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:07:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:07:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:07:10 INFO - '--tppagecycles', 19:07:10 INFO - '7'], 19:07:10 INFO - 'tests': ['tsvgm']}}, 19:07:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:07:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:07:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:07:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:07:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:07:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:07:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:10 INFO - 'talos_options': ['--disable-e10s'], 19:07:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:07:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:07:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:10 INFO - 'talos_options': ['--disable-e10s'], 19:07:10 INFO - 'tests': ['damp', 'tps']}, 19:07:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:10 INFO - 'tests': ['damp', 'tps']}, 19:07:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:07:10 INFO - 'tests': ['dromaeo_dom']}, 19:07:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:07:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:07:10 INFO - 'tests': ['basic_compositor_video']}, 19:07:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:07:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:07:10 INFO - 'tests': ['a11yr', 19:07:10 INFO - 'ts_paint', 19:07:10 INFO - 'tpaint', 19:07:10 INFO - 'sessionrestore', 19:07:10 INFO - 'sessionrestore_no_auto_restore', 19:07:10 INFO - 'tabpaint']}, 19:07:10 INFO - 'other-e10s': {'tests': ['a11yr', 19:07:10 INFO - 'ts_paint', 19:07:10 INFO - 'tpaint', 19:07:10 INFO - 'sessionrestore', 19:07:10 INFO - 'sessionrestore_no_auto_restore', 19:07:10 INFO - 'tabpaint']}, 19:07:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:07:10 INFO - 'ts_paint', 19:07:10 INFO - 'tpaint', 19:07:10 INFO - 'sessionrestore', 19:07:10 INFO - 'sessionrestore_no_auto_restore', 19:07:10 INFO - 'tabpaint']}, 19:07:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:07:10 INFO - 'ts_paint', 19:07:10 INFO - 'tpaint', 19:07:10 INFO - 'sessionrestore', 19:07:10 INFO - 'sessionrestore_no_auto_restore', 19:07:10 INFO - 'tabpaint']}, 19:07:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:07:10 INFO - 'tests': ['a11yr', 19:07:10 INFO - 'ts_paint', 19:07:10 INFO - 'tpaint', 19:07:10 INFO - 'sessionrestore', 19:07:10 INFO - 'sessionrestore_no_auto_restore', 19:07:10 INFO - 'tabpaint']}, 19:07:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:07:10 INFO - 'tests': ['a11yr', 19:07:10 INFO - 'ts_paint', 19:07:10 INFO - 'tpaint', 19:07:10 INFO - 'sessionrestore', 19:07:10 INFO - 'sessionrestore_no_auto_restore', 19:07:10 INFO - 'tabpaint']}, 19:07:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:07:10 INFO - 'tests': ['tsvgx', 19:07:10 INFO - 'tsvgr_opacity', 19:07:10 INFO - 'tart', 19:07:10 INFO - 'tscrollx', 19:07:10 INFO - 'cart']}, 19:07:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:07:10 INFO - 'tsvgr_opacity', 19:07:10 INFO - 'tart', 19:07:10 INFO - 'tscrollx', 19:07:10 INFO - 'cart']}, 19:07:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:10 INFO - 'talos_options': ['--disable-e10s'], 19:07:10 INFO - 'tests': ['tp5o']}, 19:07:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:10 INFO - 'tests': ['tp5o']}, 19:07:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:10 INFO - 'talos_options': ['--disable-e10s', 19:07:10 INFO - '--xperf_path', 19:07:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:07:10 INFO - 'tests': ['tp5n']}, 19:07:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:07:10 INFO - 'talos_options': ['--xperf_path', 19:07:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:07:10 INFO - 'tests': ['tp5n']}}, 19:07:10 INFO - 'talos.zip': {'path': '', 19:07:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:07:10 INFO - Running post-action listener: _resource_record_post_action 19:07:10 INFO - [mozharness: 2016-07-22 02:07:10.209941Z] Finished populate-webroot step (success) 19:07:10 INFO - [mozharness: 2016-07-22 02:07:10.210082Z] Running create-virtualenv step. 19:07:10 INFO - Running pre-action listener: _resource_record_pre_action 19:07:10 INFO - Running main action method: create_virtualenv 19:07:10 INFO - Creating virtualenv /builds/slave/test/build/venv 19:07:10 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 19:07:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:07:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:07:10 INFO - Using real prefix '/tools/python27' 19:07:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:07:11 INFO - Installing distribute.............................................................................................................................................................................................done. 19:07:13 INFO - Installing pip.................done. 19:07:13 INFO - Return code: 0 19:07:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:07:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1053f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105489618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb104ace10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105358d30>, '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 0x1050bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105350b20>, '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.yH2KSPLcXy/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.9N1TXglk0O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 19:07:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:07:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:07:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yH2KSPLcXy/Render', 19:07:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:13 INFO - 'HOME': '/Users/cltbld', 19:07:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:13 INFO - 'LOGNAME': 'cltbld', 19:07:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:13 INFO - 'MOZ_NO_REMOTE': '1', 19:07:13 INFO - 'NO_EM_RESTART': '1', 19:07:13 INFO - 'PAGER': '/bin/cat', 19:07:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:13 INFO - 'PWD': '/builds/slave/test', 19:07:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:13 INFO - 'SHELL': '/bin/bash', 19:07:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9N1TXglk0O/Listeners', 19:07:13 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 19:07:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:13 INFO - 'USER': 'cltbld', 19:07:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:13 INFO - 'XPC_FLAGS': '0x0', 19:07:13 INFO - 'XPC_SERVICE_NAME': '0', 19:07:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:14 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 19:07:14 INFO - Cleaning up... 19:07:14 INFO - Return code: 0 19:07:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:07:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:14 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 0x1053f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105489618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb104ace10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105358d30>, '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 0x1050bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105350b20>, '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.yH2KSPLcXy/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.9N1TXglk0O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 19:07:14 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 19:07:14 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 19:07:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yH2KSPLcXy/Render', 19:07:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:14 INFO - 'HOME': '/Users/cltbld', 19:07:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:14 INFO - 'LOGNAME': 'cltbld', 19:07:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:14 INFO - 'MOZ_NO_REMOTE': '1', 19:07:14 INFO - 'NO_EM_RESTART': '1', 19:07:14 INFO - 'PAGER': '/bin/cat', 19:07:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:14 INFO - 'PWD': '/builds/slave/test', 19:07:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:14 INFO - 'SHELL': '/bin/bash', 19:07:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9N1TXglk0O/Listeners', 19:07:14 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 19:07:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:14 INFO - 'USER': 'cltbld', 19:07:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:14 INFO - 'XPC_FLAGS': '0x0', 19:07:14 INFO - 'XPC_SERVICE_NAME': '0', 19:07:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:14 INFO - Downloading/unpacking psutil>=3.1.1 19:07:14 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 19:07:14 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 19:07:14 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 19:07:14 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 19:07:14 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 19:07:14 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 19:07:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:07:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:07:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:07:17 INFO - Installing collected packages: psutil 19:07:17 INFO - Running setup.py install for psutil 19:07:17 INFO - building 'psutil._psutil_osx' extension 19:07:17 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 19:07:18 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 19:07:18 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 19:07:18 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 19:07:18 INFO - building 'psutil._psutil_posix' extension 19:07:18 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 19:07:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:07:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:07:18 INFO - ^ 19:07:18 INFO - 1 warning generated. 19:07:18 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 19:07:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:07:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:07:18 INFO - Successfully installed psutil 19:07:18 INFO - Cleaning up... 19:07:19 INFO - Return code: 0 19:07:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:07:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:19 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 0x1053f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105489618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb104ace10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105358d30>, '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 0x1050bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105350b20>, '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.yH2KSPLcXy/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.9N1TXglk0O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 19:07:19 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 19:07:19 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 19:07:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yH2KSPLcXy/Render', 19:07:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:19 INFO - 'HOME': '/Users/cltbld', 19:07:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:19 INFO - 'LOGNAME': 'cltbld', 19:07:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:19 INFO - 'MOZ_NO_REMOTE': '1', 19:07:19 INFO - 'NO_EM_RESTART': '1', 19:07:19 INFO - 'PAGER': '/bin/cat', 19:07:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:19 INFO - 'PWD': '/builds/slave/test', 19:07:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:19 INFO - 'SHELL': '/bin/bash', 19:07:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9N1TXglk0O/Listeners', 19:07:19 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 19:07:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:19 INFO - 'USER': 'cltbld', 19:07:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:19 INFO - 'XPC_FLAGS': '0x0', 19:07:19 INFO - 'XPC_SERVICE_NAME': '0', 19:07:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:07: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 19:07: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 19:07: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 19:07: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 19:07: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 19:07: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 19:07:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:07:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:07:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:07:21 INFO - Installing collected packages: mozsystemmonitor 19:07:21 INFO - Running setup.py install for mozsystemmonitor 19:07:21 INFO - Successfully installed mozsystemmonitor 19:07:21 INFO - Cleaning up... 19:07:22 INFO - Return code: 0 19:07:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1053f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105489618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb104ace10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105358d30>, '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 0x1050bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105350b20>, '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.yH2KSPLcXy/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.9N1TXglk0O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 19:07:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:07:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:07:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yH2KSPLcXy/Render', 19:07:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:22 INFO - 'HOME': '/Users/cltbld', 19:07:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:22 INFO - 'LOGNAME': 'cltbld', 19:07:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:22 INFO - 'MOZ_NO_REMOTE': '1', 19:07:22 INFO - 'NO_EM_RESTART': '1', 19:07:22 INFO - 'PAGER': '/bin/cat', 19:07:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:22 INFO - 'PWD': '/builds/slave/test', 19:07:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:22 INFO - 'SHELL': '/bin/bash', 19:07:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9N1TXglk0O/Listeners', 19:07:22 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 19:07:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:22 INFO - 'USER': 'cltbld', 19:07:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:22 INFO - 'XPC_FLAGS': '0x0', 19:07:22 INFO - 'XPC_SERVICE_NAME': '0', 19:07:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:22 INFO - Downloading/unpacking jsonschema==2.5.1 19:07: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 19:07: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 19:07: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 19:07: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 19:07: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 19:07: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 19:07:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:07:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:07:24 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 19:07:24 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 19:07:24 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 19:07:24 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 19:07:24 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 19:07:24 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 19:07:25 INFO - Downloading functools32-3.2.3-2.tar.gz 19:07:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:07:25 INFO - warning: no files found matching '*.txt' 19:07:25 INFO - no previously-included directories found matching 'build' 19:07:25 INFO - no previously-included directories found matching 'dist' 19:07:25 INFO - no previously-included directories found matching '.git*' 19:07:25 INFO - Installing collected packages: jsonschema, functools32 19:07:25 INFO - Running setup.py install for functools32 19:07:25 INFO - warning: no files found matching '*.txt' 19:07:25 INFO - no previously-included directories found matching 'build' 19:07:25 INFO - no previously-included directories found matching 'dist' 19:07:25 INFO - no previously-included directories found matching '.git*' 19:07:25 INFO - Successfully installed jsonschema functools32 19:07:25 INFO - Cleaning up... 19:07:25 INFO - Return code: 0 19:07:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:25 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 0x1053f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105489618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb104ace10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105358d30>, '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 0x1050bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105350b20>, '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.yH2KSPLcXy/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.9N1TXglk0O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 19:07:25 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 19:07:25 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 19:07:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yH2KSPLcXy/Render', 19:07:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:25 INFO - 'HOME': '/Users/cltbld', 19:07:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:25 INFO - 'LOGNAME': 'cltbld', 19:07:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:25 INFO - 'MOZ_NO_REMOTE': '1', 19:07:25 INFO - 'NO_EM_RESTART': '1', 19:07:25 INFO - 'PAGER': '/bin/cat', 19:07:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:25 INFO - 'PWD': '/builds/slave/test', 19:07:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:25 INFO - 'SHELL': '/bin/bash', 19:07:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9N1TXglk0O/Listeners', 19:07:25 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 19:07:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:25 INFO - 'USER': 'cltbld', 19:07:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:25 INFO - 'XPC_FLAGS': '0x0', 19:07:25 INFO - 'XPC_SERVICE_NAME': '0', 19:07:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:25 INFO - Downloading/unpacking blobuploader==1.2.4 19:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:07:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:07:28 INFO - Downloading blobuploader-1.2.4.tar.gz 19:07:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:07:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:07:28 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 19:07:28 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 19:07:28 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 19:07:28 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 19:07:28 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 19:07:28 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 19:07:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:07:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:07:29 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 19:07:29 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 19:07:29 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 19:07:29 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 19:07:29 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 19:07:29 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 19:07:29 INFO - Downloading docopt-0.6.1.tar.gz 19:07:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:07:29 INFO - Installing collected packages: blobuploader, requests, docopt 19:07:29 INFO - Running setup.py install for blobuploader 19:07:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:07:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:07:29 INFO - Running setup.py install for requests 19:07:30 INFO - Running setup.py install for docopt 19:07:30 INFO - Successfully installed blobuploader requests docopt 19:07:30 INFO - Cleaning up... 19:07:30 INFO - Return code: 0 19:07:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:07:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:30 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 0x1053f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105489618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb104ace10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105358d30>, '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 0x1050bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105350b20>, '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.yH2KSPLcXy/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.9N1TXglk0O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 19:07:30 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 19:07:30 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 19:07:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yH2KSPLcXy/Render', 19:07:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:30 INFO - 'HOME': '/Users/cltbld', 19:07:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:30 INFO - 'LOGNAME': 'cltbld', 19:07:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:30 INFO - 'MOZ_NO_REMOTE': '1', 19:07:30 INFO - 'NO_EM_RESTART': '1', 19:07:30 INFO - 'PAGER': '/bin/cat', 19:07:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:30 INFO - 'PWD': '/builds/slave/test', 19:07:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:30 INFO - 'SHELL': '/bin/bash', 19:07:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9N1TXglk0O/Listeners', 19:07:30 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 19:07:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:30 INFO - 'USER': 'cltbld', 19:07:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:30 INFO - 'XPC_FLAGS': '0x0', 19:07:30 INFO - 'XPC_SERVICE_NAME': '0', 19:07:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:07:30 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-qvqhg6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:07:30 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-TsR_nM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:07:30 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-JdNZcd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:07:31 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-Jx9xNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:07:31 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-tYIDiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:07:31 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-DAr9WT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:07:31 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-Nk9zfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:07:31 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-ApzVUX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:07:31 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-bKDeWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:07:31 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-ShvgHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:07:31 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-PKmJJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:07:31 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-WtCEkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:07:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:07:32 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-UPIBw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:07:32 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-UJEaXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:32 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-JXRQOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:07:32 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-lki7Zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:07:32 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-G6Ug1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:07:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:07:32 INFO - Running setup.py install for manifestparser 19:07:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:07:32 INFO - Running setup.py install for mozcrash 19:07:32 INFO - Running setup.py install for mozdebug 19:07:32 INFO - Running setup.py install for mozdevice 19:07:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:07:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:07:33 INFO - Running setup.py install for mozfile 19:07:33 INFO - Running setup.py install for mozhttpd 19:07:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:07:33 INFO - Running setup.py install for mozinfo 19:07:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:07:33 INFO - Running setup.py install for mozInstall 19:07:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:07:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:07:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:07:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:07:33 INFO - Running setup.py install for mozleak 19:07:33 INFO - Running setup.py install for mozlog 19:07:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:07:34 INFO - Running setup.py install for moznetwork 19:07:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:07:34 INFO - Running setup.py install for mozprocess 19:07:34 INFO - Running setup.py install for mozprofile 19:07:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:07:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:07:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:07:34 INFO - Running setup.py install for mozrunner 19:07:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:07:34 INFO - Running setup.py install for mozscreenshot 19:07:34 INFO - Running setup.py install for moztest 19:07:34 INFO - Running setup.py install for mozversion 19:07:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:07:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:07:35 INFO - Cleaning up... 19:07:35 INFO - Return code: 0 19:07:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:35 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 0x1053f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105489618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb104ace10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105358d30>, '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 0x1050bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105350b20>, '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.yH2KSPLcXy/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.9N1TXglk0O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 19:07:35 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 19:07:35 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 19:07:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yH2KSPLcXy/Render', 19:07:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:35 INFO - 'HOME': '/Users/cltbld', 19:07:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:35 INFO - 'LOGNAME': 'cltbld', 19:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:35 INFO - 'MOZ_NO_REMOTE': '1', 19:07:35 INFO - 'NO_EM_RESTART': '1', 19:07:35 INFO - 'PAGER': '/bin/cat', 19:07:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:35 INFO - 'PWD': '/builds/slave/test', 19:07:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:35 INFO - 'SHELL': '/bin/bash', 19:07:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9N1TXglk0O/Listeners', 19:07:35 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 19:07:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:35 INFO - 'USER': 'cltbld', 19:07:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:35 INFO - 'XPC_FLAGS': '0x0', 19:07:35 INFO - 'XPC_SERVICE_NAME': '0', 19:07:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:07:35 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-QLP4cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:07:35 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)) 19:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:07:35 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-SiOG_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:07:35 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)) 19:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:07:35 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-JXZyfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:07:35 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)) 19:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:07:35 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-5msaKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:07:35 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)) 19:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:07:35 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-2NCOoq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:07:35 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)) 19:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:07:35 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-4wdRdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:07:35 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)) 19:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:07:35 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-FPBH1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:07:36 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)) 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:07:36 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-VSbWai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:07:36 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)) 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:07:36 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-8lOHi8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:07:36 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)) 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:07:36 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-T_3wBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:07:36 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)) 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:07:36 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-L1EZ6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:07:36 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)) 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:07:36 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-mGcdcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:07:36 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)) 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:07:36 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-FgorUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:07:36 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)) 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:07:36 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-NzsE8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:07:36 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)) 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:36 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-i0E2Mf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:07:36 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)) 19:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:07:36 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-iucUcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:07:37 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)) 19:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:07:37 INFO - Running setup.py (path:/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/pip-VRyRnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:07:37 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)) 19:07:37 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)) 19:07:37 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)) 19:07:37 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)) 19:07:37 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)) 19:07:37 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)) 19:07:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:07:37 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 19:07:37 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 19:07:37 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 19:07:37 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 19:07:37 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 19:07:37 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 19:07:39 INFO - Downloading blessings-1.6.tar.gz 19:07:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:07:39 INFO - Installing collected packages: blessings 19:07:39 INFO - Running setup.py install for blessings 19:07:39 INFO - Successfully installed blessings 19:07:39 INFO - Cleaning up... 19:07:40 INFO - Return code: 0 19:07:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:07:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:07:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:07:40 INFO - Reading from file tmpfile_stdout 19:07:40 INFO - Current package versions: 19:07:40 INFO - blessings == 1.6 19:07:40 INFO - blobuploader == 1.2.4 19:07:40 INFO - docopt == 0.6.1 19:07:40 INFO - functools32 == 3.2.3-2 19:07:40 INFO - jsonschema == 2.5.1 19:07:40 INFO - manifestparser == 1.1 19:07:40 INFO - mozInstall == 1.12 19:07:40 INFO - mozcrash == 0.17 19:07:40 INFO - mozdebug == 0.1 19:07:40 INFO - mozdevice == 0.48 19:07:40 INFO - mozfile == 1.2 19:07:40 INFO - mozhttpd == 0.7 19:07:40 INFO - mozinfo == 0.9 19:07:40 INFO - mozleak == 0.1 19:07:40 INFO - mozlog == 3.3 19:07:40 INFO - moznetwork == 0.27 19:07:40 INFO - mozprocess == 0.23 19:07:40 INFO - mozprofile == 0.28 19:07:40 INFO - mozrunner == 6.12 19:07:40 INFO - mozscreenshot == 0.1 19:07:40 INFO - mozsystemmonitor == 0.3 19:07:40 INFO - moztest == 0.7 19:07:40 INFO - mozversion == 1.4 19:07:40 INFO - psutil == 3.1.1 19:07:40 INFO - requests == 1.2.3 19:07:40 INFO - wsgiref == 0.1.2 19:07:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:40 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 0x1053f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105489618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb104ace10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105358d30>, '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 0x1050bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105350b20>, '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.yH2KSPLcXy/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.9N1TXglk0O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 19:07:40 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 19:07:40 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 19:07:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yH2KSPLcXy/Render', 19:07:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:40 INFO - 'HOME': '/Users/cltbld', 19:07:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:40 INFO - 'LOGNAME': 'cltbld', 19:07:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:40 INFO - 'MOZ_NO_REMOTE': '1', 19:07:40 INFO - 'NO_EM_RESTART': '1', 19:07:40 INFO - 'PAGER': '/bin/cat', 19:07:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:40 INFO - 'PWD': '/builds/slave/test', 19:07:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:40 INFO - 'SHELL': '/bin/bash', 19:07:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9N1TXglk0O/Listeners', 19:07:40 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 19:07:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:40 INFO - 'USER': 'cltbld', 19:07:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:40 INFO - 'XPC_FLAGS': '0x0', 19:07:40 INFO - 'XPC_SERVICE_NAME': '0', 19:07:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:40 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)) 19:07:40 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)) 19:07:40 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)) 19:07:40 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)) 19:07:40 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)) 19:07:40 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)) 19:07:40 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)) 19:07:40 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)) 19:07:40 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)) 19:07:40 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)) 19:07:40 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)) 19:07:40 INFO - Cleaning up... 19:07:40 INFO - Return code: 0 19:07:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:07:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:07:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:07:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:07:40 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 0x1053f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105489618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffb104ace10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105358d30>, '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 0x1050bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105350b20>, '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.yH2KSPLcXy/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.9N1TXglk0O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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 19:07:40 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 19:07:40 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 19:07:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yH2KSPLcXy/Render', 19:07:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:07:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:07:40 INFO - 'HOME': '/Users/cltbld', 19:07:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:07:40 INFO - 'LOGNAME': 'cltbld', 19:07:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:07:40 INFO - 'MOZ_NO_REMOTE': '1', 19:07:40 INFO - 'NO_EM_RESTART': '1', 19:07:40 INFO - 'PAGER': '/bin/cat', 19:07:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:07:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:07:40 INFO - 'PWD': '/builds/slave/test', 19:07:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:07:40 INFO - 'SHELL': '/bin/bash', 19:07:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9N1TXglk0O/Listeners', 19:07:40 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 19:07:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:07:40 INFO - 'USER': 'cltbld', 19:07:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:07:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:07:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:07:40 INFO - 'XPC_FLAGS': '0x0', 19:07:40 INFO - 'XPC_SERVICE_NAME': '0', 19:07:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:07:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:07:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:07:40 INFO - Cleaning up... 19:07:40 INFO - Return code: 0 19:07:40 INFO - Running post-action listener: _resource_record_post_action 19:07:40 INFO - Running post-action listener: _start_resource_monitoring 19:07:40 INFO - Starting resource monitoring. 19:07:40 INFO - [mozharness: 2016-07-22 02:07:40.831918Z] Finished create-virtualenv step (success) 19:07:40 INFO - [mozharness: 2016-07-22 02:07:40.832837Z] Running install step. 19:07:40 INFO - Running pre-action listener: _resource_record_pre_action 19:07:40 INFO - Running main action method: install 19:07:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:07:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:07:41 INFO - Reading from file tmpfile_stdout 19:07:41 INFO - Detecting whether we're running mozinstall >=1.0... 19:07:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:07:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:07:41 INFO - Reading from file tmpfile_stdout 19:07:41 INFO - Output received: 19:07:41 INFO - Usage: mozinstall [options] installer 19:07:41 INFO - Options: 19:07:41 INFO - -h, --help show this help message and exit 19:07:41 INFO - -d DEST, --destination=DEST 19:07:41 INFO - Directory to install application into. [default: 19:07:41 INFO - "/builds/slave/test"] 19:07:41 INFO - --app=APP Application being installed. [default: firefox] 19:07:41 INFO - mkdir: /builds/slave/test/build/application 19:07:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:07:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:08:15 INFO - Reading from file tmpfile_stdout 19:08:15 INFO - Output received: 19:08:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:08:15 INFO - Running post-action listener: _resource_record_post_action 19:08:15 INFO - [mozharness: 2016-07-22 02:08:15.551063Z] Finished install step (success) 19:08:15 INFO - [mozharness: 2016-07-22 02:08:15.551205Z] Running run-tests step. 19:08:15 INFO - Running pre-action listener: _resource_record_pre_action 19:08:15 INFO - Running main action method: run_tests 19:08:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:08:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:08:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:08:15 INFO - 2016-07-21 19:08:15.565 screenresolution[1646:7921] starting screenresolution argv=screenresolution get 19:08:15 INFO - 2016-07-21 19:08:15.567 screenresolution[1646:7921] Display 0: 1600x1200x32@60 19:08:15 INFO - 2016-07-21 19:08:15.573 screenresolution[1647:7923] starting screenresolution argv=screenresolution list 19:08:15 INFO - Available Modes on Display 0 19:08:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:08:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:08:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:08:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:08:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:08:15 INFO - Intel Iris: 19:08:15 INFO - Chipset Model: Intel Iris 19:08:15 INFO - Type: GPU 19:08:15 INFO - Bus: Built-In 19:08:15 INFO - VRAM (Dynamic, Max): 1536 MB 19:08:15 INFO - Vendor: Intel (0x8086) 19:08:15 INFO - Device ID: 0x0a2e 19:08:15 INFO - Revision ID: 0x0009 19:08:15 INFO - Displays: 19:08:15 INFO - Display: 19:08:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:08:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:08:15 INFO - Display Serial Number: 1600x1200 60 19:08:15 INFO - Main Display: Yes 19:08:15 INFO - Mirror: Off 19:08:15 INFO - Online: Yes 19:08:15 INFO - Rotation: Supported 19:08:15 INFO - Return code: 0 19:08:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:08:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:08:15 INFO - Python 2.7.3 19:08:15 INFO - Return code: 0 19:08:15 INFO - grabbing minidump binary from tooltool 19:08: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:08:15 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 0x105358d30>, '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 0x1050bddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105350b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:08:15 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 19:08:15 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 19:08:15 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 19:08:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:08:15 INFO - Return code: 0 19:08:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:08:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:08:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:08:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:08:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:08:15 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/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0197', '--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 19:08:15 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/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0197 --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 19:08:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yH2KSPLcXy/Render', 19:08:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:08:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:08:15 INFO - 'HOME': '/Users/cltbld', 19:08:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:08:15 INFO - 'LOGNAME': 'cltbld', 19:08:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:08:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:08:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:08:15 INFO - 'MOZ_NO_REMOTE': '1', 19:08:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:08:15 INFO - 'NO_EM_RESTART': '1', 19:08:15 INFO - 'PAGER': '/bin/cat', 19:08:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:08:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:08:15 INFO - 'PWD': '/builds/slave/test', 19:08:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:08:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:08:15 INFO - 'SHELL': '/bin/bash', 19:08:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9N1TXglk0O/Listeners', 19:08:15 INFO - 'TMPDIR': '/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/', 19:08:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:08:15 INFO - 'USER': 'cltbld', 19:08:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:08:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:08:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:08:15 INFO - 'XPC_FLAGS': '0x0', 19:08:15 INFO - 'XPC_SERVICE_NAME': '0', 19:08:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:08:15 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/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0197', '--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 19:08:16 INFO - mozversion application_buildid: 20160721160514 19:08:16 INFO - mozversion application_changeset: 7ba94e0c5daa48d7667519c930eca31d98648512 19:08:16 INFO - mozversion application_display_name: Nightly 19:08:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:08:16 INFO - mozversion application_name: Firefox 19:08:16 INFO - mozversion application_remotingname: firefox 19:08:16 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 19:08:16 INFO - mozversion application_vendor: Mozilla 19:08:16 INFO - mozversion application_version: 50.0a1 19:08:16 INFO - mozversion platform_buildid: 20160721160514 19:08:16 INFO - mozversion platform_changeset: 7ba94e0c5daa48d7667519c930eca31d98648512 19:08:16 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 19:08:16 INFO - mozversion platform_version: 50.0a1 19:08:16 INFO - using testdate: 1469153296 19:08:16 INFO - actual date: 1469153296 19:08:16 INFO - starting webserver on 'localhost:49268' 19:08:16 INFO - SUITE-START | Running 2 tests 19:08:16 INFO - TEST-START | dromaeo_css 19:08:16 INFO - operating with platform_type : mac_ 19:08:16 INFO - Initialising browser for dromaeo_css test... 19:08:16 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/tmpQs7i9s/profile http://localhost:49268/getInfo.html) 19:08:18 INFO - PROCESS | 1657 | JavaScript error: , line 0: uncaught exception: undefined 19:08:18 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 19:08:18 INFO - PROCESS | 1657 | colorDepth:24 19:08:18 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 19:08:18 INFO - PROCESS | 1657 | __metrics 19:08:18 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:08:18 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:08:19 INFO - TEST-INFO | 1657: exit 0 19:08:19 INFO - Browser initialized. 19:08:19 INFO - Running cycle 1/1 for dromaeo_css test... 19:08:19 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/tmpQs7i9s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:08:31 INFO - PROCESS | 1661 | RSS: Main: 245256192 19:08:31 INFO - PROCESS | 1661 | 19:09:22 INFO - PROCESS | 1661 | 1469153362223 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] 19:11:33 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49268/tests/dromaeo/cssquery-ext.html) 19:11:35 INFO - PROCESS | 1661 | RSS: Main: 366534656 19:11:35 INFO - PROCESS | 1661 | 19:14:38 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-ext.html (next: http://localhost:49268/tests/dromaeo/cssquery-jquery.html) 19:14:39 INFO - PROCESS | 1661 | RSS: Main: 385449984 19:14:39 INFO - PROCESS | 1661 | 19:17:41 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49268/tests/dromaeo/cssquery-mootools.html) 19:17:41 INFO - PROCESS | 1661 | RSS: Main: 381952000 19:17:41 INFO - PROCESS | 1661 | 19:20:44 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49268/tests/dromaeo/cssquery-prototype.html) 19:20:44 INFO - PROCESS | 1661 | RSS: Main: 379764736 19:20:44 INFO - PROCESS | 1661 | 19:23:48 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49268/tests/dromaeo/cssquery-yui.html) 19:23:48 INFO - PROCESS | 1661 | RSS: Main: 354824192 19:23:48 INFO - PROCESS | 1661 | 19:26:52 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49268/tests/dromaeo/cssquery-yui.html (next: http://localhost:49268/tests/dromaeo/cssquery-dojo.html) 19:26:52 INFO - PROCESS | 1661 | RSS: Main: 415604736 19:26:52 INFO - PROCESS | 1661 | 19:26:52 INFO - PROCESS | 1661 | __start_tp_report 19:26:52 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 19:26:52 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 19:26:52 INFO - PROCESS | 1661 | |i|pagename|runs| 19:26:52 INFO - PROCESS | 1661 | |0;dojo.html;3370.2080011197368;3373.325334933014;3374.8481318340673;3392.966070339296;3440.19155498458;5566.081596536566;5653.632513886594;5664.291963504562;5718.399568045358;5742.913856292159;5632.493075623193;5693.146028095788;5722.599418040729;5729.799457019005;5742.339630942442;5131.563817075553;5196;5213.061648903196;5224.63427560071;5315.468453154673;5316.601254905878;5317.388500322454;5321.042212401766;5336.119540275849;5369.114096174127;5136.794528218867;5192.273081768555;5193.16909294513;5197.532222100029;5275.446078161795;5229.555487783543;5237.397699264595;5240.68555886648;5248.081585722483;5255.395629502618;5136.409312929024;5192.55863251624;5194.870128246786;5203.791848326063;5205.583553315726;5704.258446401941;5714.057180565202;5717.514011309044;5717.514011309044;5725.427457254241;4896.167651499218;6142.7850025249345;6154.876902461926;6192.225971753531;6196.380361963813;6210.130581718563;6226.159468471789;6227.439530442282;6285.654289014103;6319.210098737658;5853.09277062056;5873.030949893263;5878.441548052971;5883.529317654577;5889.116632505158;6258.593191442542;6290.276618188876;6318.462930650946;6340.841478963062;6345.428911397904;6148.292946311142;6180.505559555225;6195.977663685441;6199.132121502993;6204.007358822567;6303.747850086037;6331.493480521547;6337.207849018872;6344.238691357066;6346.777862774781;6074.617028500641;6106.938930610637;6146.862830376394;6177.351378105324;6189.164462797555;6278.340774218733;6279.434850863444;6287.245530536365;6290.182276304051;6290.99344104941;4984.700917944935;4988.102141614544;4990.251462280687;4992.775325110379;5030.446650868402;4364.825406983685;4423.380726698265;4467.888302792456;4495.9325610115875;4521.773911304422;5633.436656334334;5668;5685.943140568624;5686.459786320293;5693.68684722336;5750.741216645261;6215.782447614311;6252.499800015988;6258.43674069336;6260.311365749764;5650.378458369576;5683.488486036276;5764.682942438122;5770.538356931435;5776.595638305278;5423.376311724123;5454.154606036071;5455.8363249102595;5463.153211252262;5477.643953143033;6276.184096067482;6304.65324407162;6320.905186422116;6323.968380158161;6324.810255692337;6227.501799856092;6270.811875643738;6291.811245662638;6347.5556711029785;6366.076918846648;5474.425185355481;6314.147590075281;6325.304216536086;6337.556371054166;6341.524385671001;6068.817935461943;6198.907016394848;6240.095186198067;6247.406496382836;6247.68761561911;5200.2199670049795;6119.289302195972;6236.937630623817;6303.621782693053;6319.6208227506495;6089.604175728564;6125.387461253839;6129.172561704291;6142.324344322032;6146.907796383147;5865.970670146622;6064.3935606439;6078.057901025348;6114.991026480581;6124.112003759519;5857.150713146655;5896.911546326723;5949.137375080676;6010.639361638316;6025.246844144482;6101.295876936561;6118.969405152946;6158.815235542941;6160.414760597736;6172.351903050295;5798.188253644577;5905.852353691021;5947.375525569752;5955.5235581154275;5983.042713165873 19:26:52 INFO - PROCESS | 1661 | |1;ext.html;499.5778567110791;508.8753853982698;511.5217271850822;514.115720959949;518.3572370260877;30402.84798576007;31957.36085278296;43996.680099597026;45473.54526454743;45552;36871.63128368716;44747.328790068095;45205.54794452063;45233.54766452334;45302.7734861326;9694.612215511372;9882.851757223647;9884.555195016206;9885.406875587452;9900.405975641484;8805.295576353908;8985.191332780048;11966.880331196708;12101.939490302537;12141.939290303537;12161.817572736416;12207.877921220806;12241.755164896696;12249.877501225008;12254.448549815235;12080.87919120815;12107.636770896886;12203.572874949423;12215.877841221607;12237.877621223852;8704.608292626846;8894.110588941054;8910.465372077697;9017.909820901772;11550.133739969537;40166.99582510431;42349.57650423518;42457.726268212005;42502.362464563055;42509.36235956463;3153.889613863527;3897.941530877011;4278.743275403454;4294.849680261207;4330.631896288819;31726.365472690417;32668.6733132668;32730.345393092004;33089.834550827094;33141;3029.8939537116094;3146.166265939528;3150.5274208868623;3158.1630867819817;3177.2851108500636;533234;534944;535959;537307.999999996;537671.3116434392;61623.76752464926;61938.69030654818;62012.069818952754;62167.067493987626;62241.377586223556;63972.68013659902;64231;64806;64868.67565662142;64903.67548162229;16639.41762038323;16868.662626747395;16895.23971421289;16896.831031689773;16913.577160571083;11983.46074426653;13111.2133272004;13154.473821047242;13169.73660526784;13199.538016169388;14404.351804168837;16246.83753162477;16536.338546458006;16566;16716.582085447717;5421.539169170585;5732.828015159552;6048.667323297261;6295.464885484694;6386.776462823779;851.6210286422606;883.9337049721297;990.0298406171667;1002.6991902429243;1092.251807511857;30916.917907873565;31865;32036.83981580124;32724.83637581797;33527.664723352944;1056.6724315462232;1113.5156207049797;1138.9829152562718;1163.784699830534;1218.3177420644467;12589.559365422167;15624.98437601575;15669.921650391903;15741.291641876143;16065.839341606434;687376;693895;693911;694961;695093;58910.70544647249;59141.704291478265;59176.704116479144;59191.52021199505;59299.407005930254;1275.4961790092862;1315.9473621055051;1388.4238041213187;1417.3267697843442;1447.5802017414833;29174.27064323324;30357;30576.776928922594;31313;31315.37369252648;41820.372694410216;51708.74145629398;51722;51749;51823;567290;568207.317926832;569384.153079232;572585;575505;23407;23545.882270588536;24897;25724.228273151835;26544.8672756635;37446.625533744314;40340;42502;48517.687282251114;48965;37768;37886.764661974885;38738.41892371673;39522.60477395189;39783.403248951865;37941;38333.233335333694;38928.506034947;39722.40416393698;39809.80095099506;38337.2332553353;38734.806325969315;39043.82868513949;39212.80393598014;39290.01774955534;30185.245368865955;31229.750809967347;32012.839935800173;34867;34920.65079349174;24782;25559.616605750558;25802.446449766965;25916.594742644378;26109.608355874298 19:26:52 INFO - PROCESS | 1661 | |2;jquery.html;369.918617904061;371.61166580922935;378.4493561867482;390.9784961827098;404.3227593780417;14515.637109072279;15612.375504979787;19754.506137346554;21268.57462850744;21625.56748865024;20492.823612340075;20966.37100886976;21038.368848934555;21078.683819742677;21374.251901183463;5809.6804675742715;6043.818685439422;6051;6052.152698622196;6058.182145410379;4124.257633625946;6348.857205702971;6558.639274839882;6677.5325727199115;6699.296573859748;6337.809865704036;6502.967485162568;6619.271880093186;6624;6629.73481060757;6568.671566421684;6571.967140164293;6601.2738598754095;6602.3067577904585;6622.9006564901565;5438.102713052342;5583.3858275589655;5685.545156387479;5707.543396528309;5742.167385729046;24992;25146.622800658006;25278.241652750447;25375.984960601738;25923.703814809185;2089.6656534954486;2175.3039027511204;2223.1996481266733;2256.7066281383313;2434.6713193718974;15336.463223787225;15495.380184792595;15582.922085389613;15664;16187.947783394042;1792.4085051932832;1857.0807450312047;1894.6873765828623;1917.1851962915705;1932.690769476891;111753;112608.43695781469;115078.27382589268;116346.41826790897;116771.4161429196;10616.416097114577;10642.574297028186;10830.458477076114;10922.29005114665;10961.506732197071;13220.537281195113;13269.137506062076;13278.800817987738;13302.334883255799;13355.666108347368;6849.657517124224;7141.785746427511;8603.440776349516;8685.782855428539;8773.736787896374;8771.03518612952;8784.253338466302;8795.340349473816;8976.012638609747;8990.145936136056;6370.203724534434;6512.3162067982175;6553.63954982471;6559.704813283413;6566.653835963616;1599.3362754457003;1816.6012170628806;1845.8707890447808;1848.7226915962444;1887.7451544041378;930.8464103422995;954.9522523873778;959.5825815770162;1038.6676263595728;1046.9057784799404;22005.779942200694;22433.77566224317;22482.437939051655;22527.211547595754;22604.54790904173;980.1325826643472;1016.7254841192837;1029.891861354547;1042.135027926819;1056.1550759392455;9181.622756586432;11660.830116598312;11822.763544729058;11893.108016898766;11929.105317101252;592724;596136;604974.9751251304;610048;610049;122361.38819305846;123349;127237;127932;130385.69614303925;1027.6660085472402;1043.5043354406596;1080.7460246841986;1083.6694808083453;1086.9456527173609;14634.634134146732;15651.37394504207;19434.61130777405;19668.50828729293;19840.70238946446;56384.90871562115;56915.430845691015;57022.14466783084;57746.133807993756;57748.71125644345;79685.20314796778;80156.59921700355;80510.19489805026;81713;83076;7547.019166953703;8041.312802603279;8060.204178513775;8358.373122015752;8396.992360916729;9285.907140928775;12146.514139434677;12667.87332126667;13150.342482875627;13286.933565332498;10974.670759877217;11035.889641103486;11385.267535791516;11504.597339093092;11559.959603635712;10922.890771092187;10992.010719035325;11180.217384783313;11316.886831131584;11340.773184536429;10695.577415227053;10738.19463540222;10747.89252107469;10788.676339709822;11501.712457188303;7930.206979302255;8170.918290817016;8183.549904755296;8197;8206.22040906113;8254.669813207645;11151;11487.081033517466;11513.53945842157;11634.36011019402 19:26:52 INFO - PROCESS | 1661 | |3;mootools.html;1387.535175716135;1548.9070655760656;1561.6564355841715;1599.5521254048863;1914.6457905287514;7601;8405.369597280205;8704.260137888272;8715.91284087159;8789.560521973908;8569.400141990063;8641.481511109338;8654.870176947335;8667.73996780096;8697.434666746656;2333.3349995251315;2367.763223677636;2371.2293504610984;2373;2374.5963186258377;2314.745378008418;2359.374765687094;2360.0765376218415;2371.4308565944134;2375.477394973103;2321.93034208974;2351.0830775997374;2352.1767381416453;2358.2099996501192;2367.562001029806;2290.6105961986505;2334.9299521014314;2341.2976107167865;2349.2952114365794;2353.976460235393;2012.6377252094765;2287.8103386239063;2324.3840382298704;2328.371339738278;2336.4742932840145;8029.197080291982;8168.591570421455;8562;8754.080821513713;8757.824843503158;5065.1642478990925;5228.92156617651;5255;5269.604779641542;5281.234221037928;3274.7052765251005;3453.0849324928913;3487.7907325560545;3495.8426870790877;3520.119970007498;991.6231831903903;1022.5551884930005;1036.3522798251092;1040.3497813866334;1042.6090216168936;11347;11426.228934515164;11471.08231341499;11480.081593472585;11556.653300400918;4304.698671092993;4434.7117437367115;4473.507914129443;4526.728396296233;4529.68292219548;9067.909320906707;9074;9080.137386948229;9083;9116.270698344117;4479.7984090716;5081.669691470042;5183.637145399785;5185.585153187736;5198.818041368534;4323.308270676676;4467.218236808546;4643.628509719214;4794.065157294294;4877.731724755173;3262.9673703262665;3574.6773693733485;3717.4795528626014;3763.41667041609;3858.1704933439446;1116.9497372618252;1129.5989923577176;1130.6721050895312;1131.0781712903956;1136.9943150284196;854.3592305770547;897.8238507555124;945.6075728572703;978.8776402949632;979.8052362707626;4754.857354279377;5664.68844213564;5752.188060760847;5794.594378393472;5921.082232254007;393.3784620299919;417.66169402783237;443.6229205175575;455.153414648753;458.6147635985788;3712.6287371262656;4003.199360127928;4020.5175378954714;4105.67154627629;4124.8143833526965;10254.948725256476;10578.365298082139;10642.787144257227;10689.144868410509;10699.62551310716;9512.81089863767;10064.496775161213;10430.947845260725;10466.476676166161;10561.841572376421;7959.602019898982;8143.063547692092;8152.225538573826;8154.429189956646;8214.4660597061;5790.913136303043;5860.882782344414;5929.080992446177;5940.227770389822;5967.731452084579;9794.314397992357;9861.112499875046;9865.901340986786;9901.207903367569;9909.950450247703;7751.496152750279;9928.156106731009;9955.054269844353;10087.949560252151;10177.796444071226;6828.556143850634;7081.185663648746;7083.858322833617;7391.150017747813;7542.358899493604;6588.571742836701;7276.524768989285;7755.612219389121;7761.340286075747;7841.372690184658;7056.364927156581;7411.258874112761;7490.988716523418;7525.8118547036765;7537.510061845963;6025.879482410415;7226.602536860313;7328.706851726084;7347.338739513256;7423.962880185564;6910.412614927787;7056.8588628226125;7074.3633073023675;7082.185548661764;7086.220515743367;4921.212605983025;4942.802287908444;5087.64386492942;5094.694318340911;5216.921746173886;5046.873828154326;5081.517255860687;5143.71709555978;5158.252053452154;5306.151015837448 19:26:52 INFO - PROCESS | 1661 | |4;prototype.html;294.48318201556265;300.8691219319596;315.16481324490104;321.0480924060161;323.93683231769813;4212.599803018712;4416.403785488966;4605.424321959755;5264.631475796696;5343.1718083697;4245.768727069146;4927.531884470969;5034.622403319748;5117.59059275258;5192.792288308473;3706.2587482503473;3786.9810650946706;4038.4749982502235;4044.6562042226446;4071.837126514936;3530.7351948603828;3909.061825161969;4072.1448495815916;4096.262672718909;4096.836126554934;3710.513955649708;3773.283076215524;4032.516098068236;4032.931273212601;4054.878353649395;3329.517220003098;3995.3807159889975;4041.858534951292;4041.939370909424;4043.777592232426;3005.9779674910415;3558.3594952908466;3570.036090255642;3571.4642803579413;3577.3203091412547;4457.576530229623;4937.679050861682;5119.56483698886;5198.064348417283;5223.843284701465;4407.405000324947;4423.579759922803;4602.585767280961;4630.282306242504;4651.929891198418;4599.333096700993;4839.3466881970835;5094.7197904115255;5495.340559132929;5612.915806262909;3532.6114127445962;3877.856032470428;3904.9609503905167;3937.76373417593;3949.447077409165;4636.976815115937;5084.339035925306;5502.669839809584;5806.361300256968;5834.474897259267;3925.8822235332414;3936.389859571771;3939.901502462404;3942.9605703943166;3950.762954222756;3322.7175690066133;3535.7701749386206;3891.2412079644196;4044.757314561136;4054.614811592894;4402.911941761146;4618.445786505641;4658.720476771405;4662.7668616569035;4672.76636168197;4838.467768545457;5155.97422012895;5279.39286982002;5551.694656793915;5596;1355.3765267976842;1357;1375.848656647768;1378.9172649641055;1380.6617378742274;849.7408290471466;850.9958249265926;853.4026181672854;871.5903525343078;944.3531181140941;4371.366151907955;5139.3040296702;5144.768485418165;5153.871153221199;5156.9742151289;4503.437070366204;4733.786979585927;4761.500042495557;5409.945900541023;5626.662400255916;4238.639715624145;4886.286422319063;5052.671576347526;5065.924011139836;5093.083245015932;2439;2948.292409821627;2953.18787333485;2953.793234473609;2958.2308599764215;5124.256982737482;5724.4561766632105;5734.741936612779;5743;5759.913601295984;5013.649044566845;5273;5506.449355064461;5630.915536266959;5752.424757524298;4796.834089500912;5440.510354068074;5726.341470730919;5740.019693205837;5765.60727298175;4468.927457410133;4896.510348965217;5250.97374513125;5571.1086226203615;5586.972065139648;4763.428388593391;5082.186850103965;5491.807786727445;5540.362858271189;5550.167474878801;5737.287373772612;5748.166515855261;5769.278840144982;5774.393688662798;5806.593538452268;3296.093574267096;3940.2513522430645;3941.5270167580074;3954.406838974177;3959.287328280937;4983.925241121458;5536.252605898152;5546.639468434539;5568.638038527482;5569.79992902793;4915.485427919055;4916.778744956557;5442.591805614674;5454.181872719124;5464.781408743765;4650.767461626972;5079.77141028662;5199.012187684328;5470.343558772973;5472.288602481652;4684;5329.360476742816;5339.492748188916;5355.785768569213;5368.516833485005;4960.50394960501;5320.494553017457;5322.680639161663;5480.588955828249;5504.669719816824;4534.478534968521;5410.621256512007;5425.701586412785;5431.945680543302;5436.7281635918835 19:26:52 INFO - PROCESS | 1661 | |5;yui.html;793.6031984007996;797.3979645367748;808.8544062068826;833.2833762963846;848.5163456829607;14927.626809329759;15865.920670396632;16220.83779162208;16229.594260143487;16820.990740555575;14874.107553546795;15312;15323.693526129471;15342.84657153428;15483.225838708047;716.9569825810441;903.0969030969047;912.0423555266943;913.6025828764475;916.2349438219062;749.4828568287891;895.9910400895973;927.5455027036771;932.4684929590136;939.9530023498831;969.5927710361665;970.315927271272;971;971.1502435369052;971.8299297622923;758.4463341760546;963.9469829159393;964.9517524123765;965.5365424596198;965.9758506037406;574.4628772098101;575.7610591604479;576.1703147467675;580.118220305138;696.1680791454214;13650.249236292;15690.686186276325;15701.136437495934;15708.057516549045;15717.685646287011;14433.566992990227;14593;14727.410903563845;14776.187309697962;14974.775378369333;13355.332233388292;13382.531611393651;13600.387982540711;13831.585052448443;13831.654208644764;4538.568835960578;4638.976805115953;4776.140294746944;4831.541003604652;4832.73419961902;97681;121182.78817211803;123425.38287308595;124381.37809310986;124803;11388.608392237556;11546.191766576427;11768;11901.464434100486;12173.452194651261;23534.411639709146;23851.52296954086;23874.40313992164;23898.64152037721;23909.16317928899;7594.696212151563;7753.1083925348175;7771.805704857311;7934.841303173905;7987.600619968978;4462.3752674625575;4530.456345238593;4533.795979180944;4653.650976176733;4704.717716936994;3365.2764655599162;3502.9299414011575;3516.507688923553;3521.0669172669263;3543.3976224041976;632.0866348127005;644.2365796531126;649.2987573420788;661.6460193796296;665.8035879415584;564.9661020338793;565.2877374508067;585.1544518171202;628.3465196195997;681.9454443644496;7242.674079666428;10523.10553602937;10574.577016919391;10575.418351990715;10757.677269681923;625.5371025441232;683.4498228925762;689.2797027106685;703.43022152057;714.17156098925;4278.379634952915;4746.738929358849;4749.382580264543;4840.394950631171;4884.413870335582;88334;101268;141373.5862641381;152153;158523;130555;131891;132304.01543976847;132623;133904;68667;73972;94784.52607737055;109774;110145.44927275312;9134.31492638068;9143.26853851677;9149.22231610294;9152.908470915205;9372.062793720574;60779;60981.39018609757;61110;63191.052134218946;66558;90182.1963560712;92515.53742231514;95231.04768952222;95339;98316.52525212271;10715.303505272132;11989.820152697543;12083.39583020828;12128.393580320771;12372.76577953187;11349.013922563683;13058.673533161367;13357.931365490931;13456.86543134556;13987.23070231147;9376.390534615228;12604.621861344174;12902.225866448043;12988.740225195632;13131.868681313064;9755.21225266684;13851.861481385056;14082.258748410191;14184.219867906952;14728.926355368154;13407.32963351848;13961.371738272006;13969.650758730706;13994.650133746738;14231.288435578386;10910.836337454786;11339.433028348714;11464.4267786612;11565.884341156481;11840;9589.616415343307;10940.398278094463;11325.546978121112;11359.54561817518;11682.94158529202 19:26:52 INFO - PROCESS | 1661 | __end_tp_report 19:26:52 INFO - PROCESS | 1661 | __start_cc_report 19:26:52 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1343 19:26:52 INFO - PROCESS | 1661 | __end_cc_report 19:26:52 INFO - PROCESS | 1661 | __startTimestamp1469154412516__endTimestamp 19:26:52 INFO - PROCESS | 1661 | 19:26:52 INFO - PROCESS | 1661 | ------- Summary: start ------- 19:26:52 INFO - PROCESS | 1661 | Number of tests: 6 19:26:52 INFO - PROCESS | 1661 | 19:26:52 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5745.50 Median:5896.91 stddev:633.41 (10.7%) stddev-sans-first:607.33 19:26:52 INFO - PROCESS | 1661 | Values: 3370.2 3373.3 3374.8 3393.0 3440.2 5566.1 5653.6 5664.3 5718.4 5742.9 5632.5 5693.1 5722.6 5729.8 5742.3 5131.6 5196.0 5213.1 5224.6 5315.5 5316.6 5317.4 5321.0 5336.1 5369.1 5136.8 5192.3 5193.2 5197.5 5275.4 5229.6 5237.4 5240.7 5248.1 5255.4 5136.4 5192.6 5194.9 5203.8 5205.6 5704.3 5714.1 5717.5 5717.5 5725.4 4896.2 6142.8 6154.9 6192.2 6196.4 6210.1 6226.2 6227.4 6285.7 6319.2 5853.1 5873.0 5878.4 5883.5 5889.1 6258.6 6290.3 6318.5 6340.8 6345.4 6148.3 6180.5 6196.0 6199.1 6204.0 6303.7 6331.5 6337.2 6344.2 6346.8 6074.6 6106.9 6146.9 6177.4 6189.2 6278.3 6279.4 6287.2 6290.2 6291.0 4984.7 4988.1 4990.3 4992.8 5030.4 4364.8 4423.4 4467.9 4495.9 4521.8 5633.4 5668.0 5685.9 5686.5 5693.7 5750.7 6215.8 6252.5 6258.4 6260.3 5650.4 5683.5 5764.7 5770.5 5776.6 5423.4 5454.2 5455.8 5463.2 5477.6 6276.2 6304.7 6320.9 6324.0 6324.8 6227.5 6270.8 6291.8 6347.6 6366.1 5474.4 6314.1 6325.3 6337.6 6341.5 6068.8 6198.9 6240.1 6247.4 6247.7 5200.2 6119.3 6236.9 6303.6 6319.6 6089.6 6125.4 6129.2 6142.3 6146.9 5866.0 6064.4 6078.1 6115.0 6124.1 5857.2 5896.9 5949.1 6010.6 6025.2 6101.3 6119.0 6158.8 6160.4 6172.4 5798.2 5905.9 5947.4 5955.5 5983.0 19:26:52 INFO - PROCESS | 1661 | 19:26:52 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:73086.38 Median:38536.02 stddev:161473.61 (419.0%) stddev-sans-first:161834.61 19:26:52 INFO - PROCESS | 1661 | Values: 499.6 508.9 511.5 514.1 518.4 30402.8 31957.4 43996.7 45473.5 45552.0 36871.6 44747.3 45205.5 45233.5 45302.8 9694.6 9882.9 9884.6 9885.4 9900.4 8805.3 8985.2 11966.9 12101.9 12141.9 12161.8 12207.9 12241.8 12249.9 12254.4 12080.9 12107.6 12203.6 12215.9 12237.9 8704.6 8894.1 8910.5 9017.9 11550.1 40167.0 42349.6 42457.7 42502.4 42509.4 3153.9 3897.9 4278.7 4294.8 4330.6 31726.4 32668.7 32730.3 33089.8 33141.0 3029.9 3146.2 3150.5 3158.2 3177.3 533234.0 534944.0 535959.0 537308.0 537671.3 61623.8 61938.7 62012.1 62167.1 62241.4 63972.7 64231.0 64806.0 64868.7 64903.7 16639.4 16868.7 16895.2 16896.8 16913.6 11983.5 13111.2 13154.5 13169.7 13199.5 14404.4 16246.8 16536.3 16566.0 16716.6 5421.5 5732.8 6048.7 6295.5 6386.8 851.6 883.9 990.0 1002.7 1092.3 30916.9 31865.0 32036.8 32724.8 33527.7 1056.7 1113.5 1139.0 1163.8 1218.3 12589.6 15625.0 15669.9 15741.3 16065.8 687376.0 693895.0 693911.0 694961.0 695093.0 58910.7 59141.7 59176.7 59191.5 59299.4 1275.5 1315.9 1388.4 1417.3 1447.6 29174.3 30357.0 30576.8 31313.0 31315.4 41820.4 51708.7 51722.0 51749.0 51823.0 567290.0 568207.3 569384.2 572585.0 575505.0 23407.0 23545.9 24897.0 25724.2 26544.9 37446.6 40340.0 42502.0 48517.7 48965.0 37768.0 37886.8 38738.4 39522.6 39783.4 37941.0 38333.2 38928.5 39722.4 39809.8 38337.2 38734.8 39043.8 39212.8 39290.0 30185.2 31229.8 32012.8 34867.0 34920.7 24782.0 25559.6 25802.4 25916.6 26109.6 19:26:52 INFO - PROCESS | 1661 | 19:26:52 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:35388.94 Median:12114.49 stddev:100461.06 (829.3%) stddev-sans-first:100708.47 19:26:52 INFO - PROCESS | 1661 | Values: 369.9 371.6 378.4 391.0 404.3 14515.6 15612.4 19754.5 21268.6 21625.6 20492.8 20966.4 21038.4 21078.7 21374.3 5809.7 6043.8 6051.0 6052.2 6058.2 4124.3 6348.9 6558.6 6677.5 6699.3 6337.8 6503.0 6619.3 6624.0 6629.7 6568.7 6572.0 6601.3 6602.3 6622.9 5438.1 5583.4 5685.5 5707.5 5742.2 24992.0 25146.6 25278.2 25376.0 25923.7 2089.7 2175.3 2223.2 2256.7 2434.7 15336.5 15495.4 15582.9 15664.0 16187.9 1792.4 1857.1 1894.7 1917.2 1932.7 111753.0 112608.4 115078.3 116346.4 116771.4 10616.4 10642.6 10830.5 10922.3 10961.5 13220.5 13269.1 13278.8 13302.3 13355.7 6849.7 7141.8 8603.4 8685.8 8773.7 8771.0 8784.3 8795.3 8976.0 8990.1 6370.2 6512.3 6553.6 6559.7 6566.7 1599.3 1816.6 1845.9 1848.7 1887.7 930.8 955.0 959.6 1038.7 1046.9 22005.8 22433.8 22482.4 22527.2 22604.5 980.1 1016.7 1029.9 1042.1 1056.2 9181.6 11660.8 11822.8 11893.1 11929.1 592724.0 596136.0 604975.0 610048.0 610049.0 122361.4 123349.0 127237.0 127932.0 130385.7 1027.7 1043.5 1080.7 1083.7 1086.9 14634.6 15651.4 19434.6 19668.5 19840.7 56384.9 56915.4 57022.1 57746.1 57748.7 79685.2 80156.6 80510.2 81713.0 83076.0 7547.0 8041.3 8060.2 8358.4 8397.0 9285.9 12146.5 12667.9 13150.3 13286.9 10974.7 11035.9 11385.3 11504.6 11560.0 10922.9 10992.0 11180.2 11316.9 11340.8 10695.6 10738.2 10747.9 10788.7 11501.7 7930.2 8170.9 8183.5 8197.0 8206.2 8254.7 11151.0 11487.1 11513.5 11634.4 19:26:52 INFO - PROCESS | 1661 | 19:26:52 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5539.99 Median:5073.34 stddev:3114.06 (61.4%) stddev-sans-first:3107.15 19:26:52 INFO - PROCESS | 1661 | Values: 1387.5 1548.9 1561.7 1599.6 1914.6 7601.0 8405.4 8704.3 8715.9 8789.6 8569.4 8641.5 8654.9 8667.7 8697.4 2333.3 2367.8 2371.2 2373.0 2374.6 2314.7 2359.4 2360.1 2371.4 2375.5 2321.9 2351.1 2352.2 2358.2 2367.6 2290.6 2334.9 2341.3 2349.3 2354.0 2012.6 2287.8 2324.4 2328.4 2336.5 8029.2 8168.6 8562.0 8754.1 8757.8 5065.2 5228.9 5255.0 5269.6 5281.2 3274.7 3453.1 3487.8 3495.8 3520.1 991.6 1022.6 1036.4 1040.3 1042.6 11347.0 11426.2 11471.1 11480.1 11556.7 4304.7 4434.7 4473.5 4526.7 4529.7 9067.9 9074.0 9080.1 9083.0 9116.3 4479.8 5081.7 5183.6 5185.6 5198.8 4323.3 4467.2 4643.6 4794.1 4877.7 3263.0 3574.7 3717.5 3763.4 3858.2 1116.9 1129.6 1130.7 1131.1 1137.0 854.4 897.8 945.6 978.9 979.8 4754.9 5664.7 5752.2 5794.6 5921.1 393.4 417.7 443.6 455.2 458.6 3712.6 4003.2 4020.5 4105.7 4124.8 10254.9 10578.4 10642.8 10689.1 10699.6 9512.8 10064.5 10430.9 10466.5 10561.8 7959.6 8143.1 8152.2 8154.4 8214.5 5790.9 5860.9 5929.1 5940.2 5967.7 9794.3 9861.1 9865.9 9901.2 9910.0 7751.5 9928.2 9955.1 10087.9 10177.8 6828.6 7081.2 7083.9 7391.2 7542.4 6588.6 7276.5 7755.6 7761.3 7841.4 7056.4 7411.3 7491.0 7525.8 7537.5 6025.9 7226.6 7328.7 7347.3 7424.0 6910.4 7056.9 7074.4 7082.2 7086.2 4921.2 4942.8 5087.6 5094.7 5216.9 5046.9 5081.5 5143.7 5158.3 5306.2 19:26:52 INFO - PROCESS | 1661 | 19:26:52 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4410.00 Median:4891.40 stddev:1316.73 (26.9%) stddev-sans-first:1283.68 19:26:52 INFO - PROCESS | 1661 | Values: 294.5 300.9 315.2 321.0 323.9 4212.6 4416.4 4605.4 5264.6 5343.2 4245.8 4927.5 5034.6 5117.6 5192.8 3706.3 3787.0 4038.5 4044.7 4071.8 3530.7 3909.1 4072.1 4096.3 4096.8 3710.5 3773.3 4032.5 4032.9 4054.9 3329.5 3995.4 4041.9 4041.9 4043.8 3006.0 3558.4 3570.0 3571.5 3577.3 4457.6 4937.7 5119.6 5198.1 5223.8 4407.4 4423.6 4602.6 4630.3 4651.9 4599.3 4839.3 5094.7 5495.3 5612.9 3532.6 3877.9 3905.0 3937.8 3949.4 4637.0 5084.3 5502.7 5806.4 5834.5 3925.9 3936.4 3939.9 3943.0 3950.8 3322.7 3535.8 3891.2 4044.8 4054.6 4402.9 4618.4 4658.7 4662.8 4672.8 4838.5 5156.0 5279.4 5551.7 5596.0 1355.4 1357.0 1375.8 1378.9 1380.7 849.7 851.0 853.4 871.6 944.4 4371.4 5139.3 5144.8 5153.9 5157.0 4503.4 4733.8 4761.5 5409.9 5626.7 4238.6 4886.3 5052.7 5065.9 5093.1 2439.0 2948.3 2953.2 2953.8 2958.2 5124.3 5724.5 5734.7 5743.0 5759.9 5013.6 5273.0 5506.4 5630.9 5752.4 4796.8 5440.5 5726.3 5740.0 5765.6 4468.9 4896.5 5251.0 5571.1 5587.0 4763.4 5082.2 5491.8 5540.4 5550.2 5737.3 5748.2 5769.3 5774.4 5806.6 3296.1 3940.3 3941.5 3954.4 3959.3 4983.9 5536.3 5546.6 5568.6 5569.8 4915.5 4916.8 5442.6 5454.2 5464.8 4650.8 5079.8 5199.0 5470.3 5472.3 4684.0 5329.4 5339.5 5355.8 5368.5 4960.5 5320.5 5322.7 5480.6 5504.7 4534.5 5410.6 5425.7 5431.9 5436.7 19:26:52 INFO - PROCESS | 1661 | 19:26:52 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:24327.98 Median:3910.89 stddev:38217.82 (977.2%) stddev-sans-first:38284.18 19:26:52 INFO - PROCESS | 1661 | Values: 793.6 797.4 808.9 833.3 848.5 14927.6 15865.9 16220.8 16229.6 16821.0 14874.1 15312.0 15323.7 15342.8 15483.2 717.0 903.1 912.0 913.6 916.2 749.5 896.0 927.5 932.5 940.0 969.6 970.3 971.0 971.2 971.8 758.4 963.9 965.0 965.5 966.0 574.5 575.8 576.2 580.1 696.2 13650.2 15690.7 15701.1 15708.1 15717.7 14433.6 14593.0 14727.4 14776.2 14974.8 13355.3 13382.5 13600.4 13831.6 13831.7 4538.6 4639.0 4776.1 4831.5 4832.7 97681.0 121182.8 123425.4 124381.4 124803.0 11388.6 11546.2 11768.0 11901.5 12173.5 23534.4 23851.5 23874.4 23898.6 23909.2 7594.7 7753.1 7771.8 7934.8 7987.6 4462.4 4530.5 4533.8 4653.7 4704.7 3365.3 3502.9 3516.5 3521.1 3543.4 632.1 644.2 649.3 661.6 665.8 565.0 565.3 585.2 628.3 681.9 7242.7 10523.1 10574.6 10575.4 10757.7 625.5 683.4 689.3 703.4 714.2 4278.4 4746.7 4749.4 4840.4 4884.4 88334.0 101268.0 141373.6 152153.0 158523.0 130555.0 131891.0 132304.0 132623.0 133904.0 68667.0 73972.0 94784.5 109774.0 110145.4 9134.3 9143.3 9149.2 9152.9 9372.1 60779.0 60981.4 61110.0 63191.1 66558.0 90182.2 92515.5 95231.0 95339.0 98316.5 10715.3 11989.8 12083.4 12128.4 12372.8 11349.0 13058.7 13357.9 13456.9 13987.2 9376.4 12604.6 12902.2 12988.7 13131.9 9755.2 13851.9 14082.3 14184.2 14728.9 13407.3 13961.4 13969.7 13994.7 14231.3 10910.8 11339.4 11464.4 11565.9 11840.0 9589.6 10940.4 11325.5 11359.5 11682.9 19:26:52 INFO - PROCESS | 1661 | -------- Summary: end -------- 19:26:52 INFO - PROCESS | 1661 | 19:26:53 INFO - TEST-INFO | 1661: exit 0 19:26:53 INFO - TEST-OK | dromaeo_css | took 1117070ms 19:26:53 INFO - TEST-START | kraken 19:26:53 INFO - operating with platform_type : mac_ 19:26:53 INFO - Initialising browser for kraken test... 19:26:53 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/tmpe5cWOO/profile http://localhost:49268/getInfo.html) 19:26:55 INFO - PROCESS | 1667 | JavaScript error: , line 0: uncaught exception: undefined 19:26:55 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 19:26:55 INFO - PROCESS | 1667 | colorDepth:24 19:26:55 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 19:26:55 INFO - PROCESS | 1667 | __metrics 19:26:55 INFO - PROCESS | 1667 | 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" 19:26:55 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:26:56 INFO - TEST-INFO | 1667: exit 0 19:26:56 INFO - Browser initialized. 19:26:56 INFO - Running cycle 1/1 for kraken test... 19:26:56 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/T/tmpe5cWOO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:27:08 INFO - PROCESS | 1668 | RSS: Main: 245653504 19:27:08 INFO - PROCESS | 1668 | 19:27:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection) 19:27:12 INFO - PROCESS | 1668 | RSS: Main: 324952064 19:27:12 INFO - PROCESS | 1668 | 19:27:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-dft) 19:27:15 INFO - PROCESS | 1668 | RSS: Main: 335663104 19:27:15 INFO - PROCESS | 1668 | 19:27:18 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-fft) 19:27:19 INFO - PROCESS | 1668 | RSS: Main: 427728896 19:27:19 INFO - PROCESS | 1668 | 19:27:21 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator) 19:27:21 INFO - PROCESS | 1668 | RSS: Main: 329973760 19:27:21 INFO - PROCESS | 1668 | 19:27:24 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:27:24 INFO - PROCESS | 1668 | RSS: Main: 333799424 19:27:24 INFO - PROCESS | 1668 | 19:27:27 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom) 19:27:28 INFO - PROCESS | 1668 | RSS: Main: 371744768 19:27:28 INFO - PROCESS | 1668 | 19:27:31 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate) 19:27:31 INFO - PROCESS | 1668 | RSS: Main: 372670464 19:27:31 INFO - PROCESS | 1668 | 19:27:34 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial) 19:27:35 INFO - PROCESS | 1668 | RSS: Main: 373063680 19:27:35 INFO - PROCESS | 1668 | 19:27:36 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:27:37 INFO - PROCESS | 1668 | RSS: Main: 350834688 19:27:37 INFO - PROCESS | 1668 | 19:27:39 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:27:39 INFO - PROCESS | 1668 | RSS: Main: 356810752 19:27:39 INFO - PROCESS | 1668 | 19:27:41 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:27:42 INFO - PROCESS | 1668 | RSS: Main: 355860480 19:27:42 INFO - PROCESS | 1668 | 19:27:45 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:27:46 INFO - PROCESS | 1668 | RSS: Main: 364556288 19:27:46 INFO - PROCESS | 1668 | 19:27:48 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:27:49 INFO - PROCESS | 1668 | RSS: Main: 365797376 19:27:49 INFO - PROCESS | 1668 | 19:27:51 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49268/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49268/tests/kraken/driver.html?testName=ai-astar) 19:27:51 INFO - PROCESS | 1668 | RSS: Main: 409788416 19:27:51 INFO - PROCESS | 1668 | 19:27:51 INFO - PROCESS | 1668 | __start_tp_report 19:27:51 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 19:27:51 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 19:27:51 INFO - PROCESS | 1668 | |i|pagename|runs| 19:27:51 INFO - PROCESS | 1668 | |0;ai-astar;102;111;112;112;113;114;114;112;112;119 19:27:51 INFO - PROCESS | 1668 | |1;audio-beat-detection;140;115;107;160;108;108;128;106;141;107 19:27:51 INFO - PROCESS | 1668 | |2;audio-dft;185;185;187;182;182;180;183;185;184;184 19:27:51 INFO - PROCESS | 1668 | |3;audio-fft;80;57;58;103;59;58;66;70;84;56 19:27:51 INFO - PROCESS | 1668 | |4;audio-oscillator;85;68;92;71;70;68;97;78;98;135 19:27:51 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;89;87;91;87;90;86;90;88;135;86 19:27:51 INFO - PROCESS | 1668 | |6;imaging-darkroom;95;94;95;95;94;94;95;95;94;94 19:27:51 INFO - PROCESS | 1668 | |7;imaging-desaturate;85;86;86;87;87;85;86;85;86;90 19:27:51 INFO - PROCESS | 1668 | |8;json-parse-financial;54;52;52;53;55;55;54;52;52;53 19:27:51 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;68;70;76;67;78;72;73;72;72;72 19:27:51 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;79;76;83;82;82;85;85;84;83;81 19:27:51 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;136;137;137;133;132;136;131;132;177;136 19:27:51 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;162;162;162;163;163;160;162;159;157;222 19:27:51 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;66;67;66;67;69;64;65;66;65;65 19:27:51 INFO - PROCESS | 1668 | __end_tp_report 19:27:51 INFO - PROCESS | 1668 | __start_cc_report 19:27:51 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,2097 19:27:51 INFO - PROCESS | 1668 | __end_cc_report 19:27:51 INFO - PROCESS | 1668 | __startTimestamp1469154471509__endTimestamp 19:27:51 INFO - PROCESS | 1668 | 19:27:51 INFO - PROCESS | 1668 | ------- Summary: start ------- 19:27:51 INFO - PROCESS | 1668 | Number of tests: 14 19:27:51 INFO - PROCESS | 1668 | 19:27:51 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:112.10 Median:112.50 stddev:4.20 (3.7%) stddev-sans-first:2.39 19:27:51 INFO - PROCESS | 1668 | Values: 102.0 111.0 112.0 112.0 113.0 114.0 114.0 112.0 112.0 119.0 19:27:51 INFO - PROCESS | 1668 | 19:27:51 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:122.00 Median:121.50 stddev:19.18 (15.8%) stddev-sans-first:19.21 19:27:51 INFO - PROCESS | 1668 | Values: 140.0 115.0 107.0 160.0 108.0 108.0 128.0 106.0 141.0 107.0 19:27:51 INFO - PROCESS | 1668 | 19:27:51 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.50 stddev:2.00 (1.1%) stddev-sans-first:2.07 19:27:51 INFO - PROCESS | 1668 | Values: 185.0 185.0 187.0 182.0 182.0 180.0 183.0 185.0 184.0 184.0 19:27:51 INFO - PROCESS | 1668 | 19:27:51 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:69.10 Median:62.50 stddev:15.52 (24.8%) stddev-sans-first:15.95 19:27:51 INFO - PROCESS | 1668 | Values: 80.0 57.0 58.0 103.0 59.0 58.0 66.0 70.0 84.0 56.0 19:27:51 INFO - PROCESS | 1668 | 19:27:51 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:86.20 Median:81.50 stddev:20.80 (25.5%) stddev-sans-first:22.06 19:27:51 INFO - PROCESS | 1668 | Values: 85.0 68.0 92.0 71.0 70.0 68.0 97.0 78.0 98.0 135.0 19:27:51 INFO - PROCESS | 1668 | 19:27:51 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:92.90 Median:88.50 stddev:14.90 (16.8%) stddev-sans-first:15.73 19:27:51 INFO - PROCESS | 1668 | Values: 89.0 87.0 91.0 87.0 90.0 86.0 90.0 88.0 135.0 86.0 19:27:51 INFO - PROCESS | 1668 | 19:27:51 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 19:27:51 INFO - PROCESS | 1668 | Values: 95.0 94.0 95.0 95.0 94.0 94.0 95.0 95.0 94.0 94.0 19:27:51 INFO - PROCESS | 1668 | 19:27:51 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:86.30 Median:86.00 stddev:1.49 (1.7%) stddev-sans-first:1.51 19:27:51 INFO - PROCESS | 1668 | Values: 85.0 86.0 86.0 87.0 87.0 85.0 86.0 85.0 86.0 90.0 19:27:51 INFO - PROCESS | 1668 | 19:27:51 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:53.20 Median:53.50 stddev:1.23 (2.3%) stddev-sans-first:1.27 19:27:51 INFO - PROCESS | 1668 | Values: 54.0 52.0 52.0 53.0 55.0 55.0 54.0 52.0 52.0 53.0 19:27:51 INFO - PROCESS | 1668 | 19:27:51 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:72.00 stddev:3.30 (4.6%) stddev-sans-first:3.17 19:27:51 INFO - PROCESS | 1668 | Values: 68.0 70.0 76.0 67.0 78.0 72.0 73.0 72.0 72.0 72.0 19:27:51 INFO - PROCESS | 1668 | 19:27:51 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:83.00 stddev:2.79 (3.4%) stddev-sans-first:2.74 19:27:51 INFO - PROCESS | 1668 | Values: 79.0 76.0 83.0 82.0 82.0 85.0 85.0 84.0 83.0 81.0 19:27:51 INFO - PROCESS | 1668 | 19:27:51 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:138.70 Median:136.00 stddev:13.65 (10.0%) stddev-sans-first:14.44 19:27:51 INFO - PROCESS | 1668 | Values: 136.0 137.0 137.0 133.0 132.0 136.0 131.0 132.0 177.0 136.0 19:27:51 INFO - PROCESS | 1668 | 19:27:51 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.20 Median:162.00 stddev:19.35 (11.9%) stddev-sans-first:20.43 19:27:51 INFO - PROCESS | 1668 | Values: 162.0 162.0 162.0 163.0 163.0 160.0 162.0 159.0 157.0 222.0 19:27:51 INFO - PROCESS | 1668 | 19:27:51 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.00 stddev:1.41 (2.1%) stddev-sans-first:1.50 19:27:51 INFO - PROCESS | 1668 | Values: 66.0 67.0 66.0 67.0 69.0 64.0 65.0 66.0 65.0 65.0 19:27:51 INFO - PROCESS | 1668 | -------- Summary: end -------- 19:27:51 INFO - PROCESS | 1668 | 19:27:52 INFO - TEST-INFO | 1668: exit 0 19:27:52 INFO - TEST-OK | kraken | took 59262ms 19:27:52 INFO - SUITE-END | took 1176s 19:27:52 INFO - Completed test suite (00:19:36) 19:27:52 INFO - javascript benchmark 19:27:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3370.2080011197368, 3373.325334933014, 3374.8481318340673, 3392.966070339296, 3440.19155498458, 5566.081596536566, 5653.632513886594, 5664.291963504562, 5718.399568045358, 5742.913856292159, 5632.493075623193, 5693.146028095788, 5722.599418040729, 5729.799457019005, 5742.339630942442, 5131.563817075553, 5196.0, 5213.061648903196, 5224.63427560071, 5315.468453154673, 5316.601254905878, 5317.388500322454, 5321.042212401766, 5336.119540275849, 5369.114096174127, 5136.794528218867, 5192.273081768555, 5193.16909294513, 5197.532222100029, 5275.446078161795, 5229.555487783543, 5237.397699264595, 5240.68555886648, 5248.081585722483, 5255.395629502618, 5136.409312929024, 5192.55863251624, 5194.870128246786, 5203.791848326063, 5205.583553315726, 5704.258446401941, 5714.057180565202, 5717.514011309044, 5717.514011309044, 5725.427457254241, 4896.167651499218, 6142.7850025249345, 6154.876902461926, 6192.225971753531, 6196.380361963813, 6210.130581718563, 6226.159468471789, 6227.439530442282, 6285.654289014103, 6319.210098737658, 5853.09277062056, 5873.030949893263, 5878.441548052971, 5883.529317654577, 5889.116632505158, 6258.593191442542, 6290.276618188876, 6318.462930650946, 6340.841478963062, 6345.428911397904, 6148.292946311142, 6180.505559555225, 6195.977663685441, 6199.132121502993, 6204.007358822567, 6303.747850086037, 6331.493480521547, 6337.207849018872, 6344.238691357066, 6346.777862774781, 6074.617028500641, 6106.938930610637, 6146.862830376394, 6177.351378105324, 6189.164462797555, 6278.340774218733, 6279.434850863444, 6287.245530536365, 6290.182276304051, 6290.99344104941, 4984.700917944935, 4988.102141614544, 4990.251462280687, 4992.775325110379, 5030.446650868402, 4364.825406983685, 4423.380726698265, 4467.888302792456, 4495.9325610115875, 4521.773911304422, 5633.436656334334, 5668.0, 5685.943140568624, 5686.459786320293, 5693.68684722336, 5750.741216645261, 6215.782447614311, 6252.499800015988, 6258.43674069336, 6260.311365749764, 5650.378458369576, 5683.488486036276, 5764.682942438122, 5770.538356931435, 5776.595638305278, 5423.376311724123, 5454.154606036071, 5455.8363249102595, 5463.153211252262, 5477.643953143033, 6276.184096067482, 6304.65324407162, 6320.905186422116, 6323.968380158161, 6324.810255692337, 6227.501799856092, 6270.811875643738, 6291.811245662638, 6347.5556711029785, 6366.076918846648, 5474.425185355481, 6314.147590075281, 6325.304216536086, 6337.556371054166, 6341.524385671001, 6068.817935461943, 6198.907016394848, 6240.095186198067, 6247.406496382836, 6247.68761561911, 5200.2199670049795, 6119.289302195972, 6236.937630623817, 6303.621782693053, 6319.6208227506495, 6089.604175728564, 6125.387461253839, 6129.172561704291, 6142.324344322032, 6146.907796383147, 5865.970670146622, 6064.3935606439, 6078.057901025348, 6114.991026480581, 6124.112003759519, 5857.150713146655, 5896.911546326723, 5949.137375080676, 6010.639361638316, 6025.246844144482, 6101.295876936561, 6118.969405152946, 6158.815235542941, 6160.414760597736, 6172.351903050295, 5798.188253644577, 5905.852353691021, 5947.375525569752, 5955.5235581154275, 5983.042713165873], "value": 5706.096438470626, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.5778567110791, 508.8753853982698, 511.5217271850822, 514.115720959949, 518.3572370260877, 30402.84798576007, 31957.36085278296, 43996.680099597026, 45473.54526454743, 45552.0, 36871.63128368716, 44747.328790068095, 45205.54794452063, 45233.54766452334, 45302.7734861326, 9694.612215511372, 9882.851757223647, 9884.555195016206, 9885.406875587452, 9900.405975641484, 8805.295576353908, 8985.191332780048, 11966.880331196708, 12101.939490302537, 12141.939290303537, 12161.817572736416, 12207.877921220806, 12241.755164896696, 12249.877501225008, 12254.448549815235, 12080.87919120815, 12107.636770896886, 12203.572874949423, 12215.877841221607, 12237.877621223852, 8704.608292626846, 8894.110588941054, 8910.465372077697, 9017.909820901772, 11550.133739969537, 40166.99582510431, 42349.57650423518, 42457.726268212005, 42502.362464563055, 42509.36235956463, 3153.889613863527, 3897.941530877011, 4278.743275403454, 4294.849680261207, 4330.631896288819, 31726.365472690417, 32668.6733132668, 32730.345393092004, 33089.834550827094, 33141.0, 3029.8939537116094, 3146.166265939528, 3150.5274208868623, 3158.1630867819817, 3177.2851108500636, 533234.0, 534944.0, 535959.0, 537307.999999996, 537671.3116434392, 61623.76752464926, 61938.69030654818, 62012.069818952754, 62167.067493987626, 62241.377586223556, 63972.68013659902, 64231.0, 64806.0, 64868.67565662142, 64903.67548162229, 16639.41762038323, 16868.662626747395, 16895.23971421289, 16896.831031689773, 16913.577160571083, 11983.46074426653, 13111.2133272004, 13154.473821047242, 13169.73660526784, 13199.538016169388, 14404.351804168837, 16246.83753162477, 16536.338546458006, 16566.0, 16716.582085447717, 5421.539169170585, 5732.828015159552, 6048.667323297261, 6295.464885484694, 6386.776462823779, 851.6210286422606, 883.9337049721297, 990.0298406171667, 1002.6991902429243, 1092.251807511857, 30916.917907873565, 31865.0, 32036.83981580124, 32724.83637581797, 33527.664723352944, 1056.6724315462232, 1113.5156207049797, 1138.9829152562718, 1163.784699830534, 1218.3177420644467, 12589.559365422167, 15624.98437601575, 15669.921650391903, 15741.291641876143, 16065.839341606434, 687376.0, 693895.0, 693911.0, 694961.0, 695093.0, 58910.70544647249, 59141.704291478265, 59176.704116479144, 59191.52021199505, 59299.407005930254, 1275.4961790092862, 1315.9473621055051, 1388.4238041213187, 1417.3267697843442, 1447.5802017414833, 29174.27064323324, 30357.0, 30576.776928922594, 31313.0, 31315.37369252648, 41820.372694410216, 51708.74145629398, 51722.0, 51749.0, 51823.0, 567290.0, 568207.317926832, 569384.153079232, 572585.0, 575505.0, 23407.0, 23545.882270588536, 24897.0, 25724.228273151835, 26544.8672756635, 37446.625533744314, 40340.0, 42502.0, 48517.687282251114, 48965.0, 37768.0, 37886.764661974885, 38738.41892371673, 39522.60477395189, 39783.403248951865, 37941.0, 38333.233335333694, 38928.506034947, 39722.40416393698, 39809.80095099506, 38337.2332553353, 38734.806325969315, 39043.82868513949, 39212.80393598014, 39290.01774955534, 30185.245368865955, 31229.750809967347, 32012.839935800173, 34867.0, 34920.65079349174, 24782.0, 25559.616605750558, 25802.446449766965, 25916.594742644378, 26109.608355874298], "value": 20398.47030943256, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.918617904061, 371.61166580922935, 378.4493561867482, 390.9784961827098, 404.3227593780417, 14515.637109072279, 15612.375504979787, 19754.506137346554, 21268.57462850744, 21625.56748865024, 20492.823612340075, 20966.37100886976, 21038.368848934555, 21078.683819742677, 21374.251901183463, 5809.6804675742715, 6043.818685439422, 6051.0, 6052.152698622196, 6058.182145410379, 4124.257633625946, 6348.857205702971, 6558.639274839882, 6677.5325727199115, 6699.296573859748, 6337.809865704036, 6502.967485162568, 6619.271880093186, 6624.0, 6629.73481060757, 6568.671566421684, 6571.967140164293, 6601.2738598754095, 6602.3067577904585, 6622.9006564901565, 5438.102713052342, 5583.3858275589655, 5685.545156387479, 5707.543396528309, 5742.167385729046, 24992.0, 25146.622800658006, 25278.241652750447, 25375.984960601738, 25923.703814809185, 2089.6656534954486, 2175.3039027511204, 2223.1996481266733, 2256.7066281383313, 2434.6713193718974, 15336.463223787225, 15495.380184792595, 15582.922085389613, 15664.0, 16187.947783394042, 1792.4085051932832, 1857.0807450312047, 1894.6873765828623, 1917.1851962915705, 1932.690769476891, 111753.0, 112608.43695781469, 115078.27382589268, 116346.41826790897, 116771.4161429196, 10616.416097114577, 10642.574297028186, 10830.458477076114, 10922.29005114665, 10961.506732197071, 13220.537281195113, 13269.137506062076, 13278.800817987738, 13302.334883255799, 13355.666108347368, 6849.657517124224, 7141.785746427511, 8603.440776349516, 8685.782855428539, 8773.736787896374, 8771.03518612952, 8784.253338466302, 8795.340349473816, 8976.012638609747, 8990.145936136056, 6370.203724534434, 6512.3162067982175, 6553.63954982471, 6559.704813283413, 6566.653835963616, 1599.3362754457003, 1816.6012170628806, 1845.8707890447808, 1848.7226915962444, 1887.7451544041378, 930.8464103422995, 954.9522523873778, 959.5825815770162, 1038.6676263595728, 1046.9057784799404, 22005.779942200694, 22433.77566224317, 22482.437939051655, 22527.211547595754, 22604.54790904173, 980.1325826643472, 1016.7254841192837, 1029.891861354547, 1042.135027926819, 1056.1550759392455, 9181.622756586432, 11660.830116598312, 11822.763544729058, 11893.108016898766, 11929.105317101252, 592724.0, 596136.0, 604974.9751251304, 610048.0, 610049.0, 122361.38819305846, 123349.0, 127237.0, 127932.0, 130385.69614303925, 1027.6660085472402, 1043.5043354406596, 1080.7460246841986, 1083.6694808083453, 1086.9456527173609, 14634.634134146732, 15651.37394504207, 19434.61130777405, 19668.50828729293, 19840.70238946446, 56384.90871562115, 56915.430845691015, 57022.14466783084, 57746.133807993756, 57748.71125644345, 79685.20314796778, 80156.59921700355, 80510.19489805026, 81713.0, 83076.0, 7547.019166953703, 8041.312802603279, 8060.204178513775, 8358.373122015752, 8396.992360916729, 9285.907140928775, 12146.514139434677, 12667.87332126667, 13150.342482875627, 13286.933565332498, 10974.670759877217, 11035.889641103486, 11385.267535791516, 11504.597339093092, 11559.959603635712, 10922.890771092187, 10992.010719035325, 11180.217384783313, 11316.886831131584, 11340.773184536429, 10695.577415227053, 10738.19463540222, 10747.89252107469, 10788.676339709822, 11501.712457188303, 7930.206979302255, 8170.918290817016, 8183.549904755296, 8197.0, 8206.22040906113, 8254.669813207645, 11151.0, 11487.081033517466, 11513.53945842157, 11634.36011019402], "value": 9865.098364647885, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1387.535175716135, 1548.9070655760656, 1561.6564355841715, 1599.5521254048863, 1914.6457905287514, 7601.0, 8405.369597280205, 8704.260137888272, 8715.91284087159, 8789.560521973908, 8569.400141990063, 8641.481511109338, 8654.870176947335, 8667.73996780096, 8697.434666746656, 2333.3349995251315, 2367.763223677636, 2371.2293504610984, 2373.0, 2374.5963186258377, 2314.745378008418, 2359.374765687094, 2360.0765376218415, 2371.4308565944134, 2375.477394973103, 2321.93034208974, 2351.0830775997374, 2352.1767381416453, 2358.2099996501192, 2367.562001029806, 2290.6105961986505, 2334.9299521014314, 2341.2976107167865, 2349.2952114365794, 2353.976460235393, 2012.6377252094765, 2287.8103386239063, 2324.3840382298704, 2328.371339738278, 2336.4742932840145, 8029.197080291982, 8168.591570421455, 8562.0, 8754.080821513713, 8757.824843503158, 5065.1642478990925, 5228.92156617651, 5255.0, 5269.604779641542, 5281.234221037928, 3274.7052765251005, 3453.0849324928913, 3487.7907325560545, 3495.8426870790877, 3520.119970007498, 991.6231831903903, 1022.5551884930005, 1036.3522798251092, 1040.3497813866334, 1042.6090216168936, 11347.0, 11426.228934515164, 11471.08231341499, 11480.081593472585, 11556.653300400918, 4304.698671092993, 4434.7117437367115, 4473.507914129443, 4526.728396296233, 4529.68292219548, 9067.909320906707, 9074.0, 9080.137386948229, 9083.0, 9116.270698344117, 4479.7984090716, 5081.669691470042, 5183.637145399785, 5185.585153187736, 5198.818041368534, 4323.308270676676, 4467.218236808546, 4643.628509719214, 4794.065157294294, 4877.731724755173, 3262.9673703262665, 3574.6773693733485, 3717.4795528626014, 3763.41667041609, 3858.1704933439446, 1116.9497372618252, 1129.5989923577176, 1130.6721050895312, 1131.0781712903956, 1136.9943150284196, 854.3592305770547, 897.8238507555124, 945.6075728572703, 978.8776402949632, 979.8052362707626, 4754.857354279377, 5664.68844213564, 5752.188060760847, 5794.594378393472, 5921.082232254007, 393.3784620299919, 417.66169402783237, 443.6229205175575, 455.153414648753, 458.6147635985788, 3712.6287371262656, 4003.199360127928, 4020.5175378954714, 4105.67154627629, 4124.8143833526965, 10254.948725256476, 10578.365298082139, 10642.787144257227, 10689.144868410509, 10699.62551310716, 9512.81089863767, 10064.496775161213, 10430.947845260725, 10466.476676166161, 10561.841572376421, 7959.602019898982, 8143.063547692092, 8152.225538573826, 8154.429189956646, 8214.4660597061, 5790.913136303043, 5860.882782344414, 5929.080992446177, 5940.227770389822, 5967.731452084579, 9794.314397992357, 9861.112499875046, 9865.901340986786, 9901.207903367569, 9909.950450247703, 7751.496152750279, 9928.156106731009, 9955.054269844353, 10087.949560252151, 10177.796444071226, 6828.556143850634, 7081.185663648746, 7083.858322833617, 7391.150017747813, 7542.358899493604, 6588.571742836701, 7276.524768989285, 7755.612219389121, 7761.340286075747, 7841.372690184658, 7056.364927156581, 7411.258874112761, 7490.988716523418, 7525.8118547036765, 7537.510061845963, 6025.879482410415, 7226.602536860313, 7328.706851726084, 7347.338739513256, 7423.962880185564, 6910.412614927787, 7056.8588628226125, 7074.3633073023675, 7082.185548661764, 7086.220515743367, 4921.212605983025, 4942.802287908444, 5087.64386492942, 5094.694318340911, 5216.921746173886, 5046.873828154326, 5081.517255860687, 5143.71709555978, 5158.252053452154, 5306.151015837448], "value": 4382.129461995887, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.48318201556265, 300.8691219319596, 315.16481324490104, 321.0480924060161, 323.93683231769813, 4212.599803018712, 4416.403785488966, 4605.424321959755, 5264.631475796696, 5343.1718083697, 4245.768727069146, 4927.531884470969, 5034.622403319748, 5117.59059275258, 5192.792288308473, 3706.2587482503473, 3786.9810650946706, 4038.4749982502235, 4044.6562042226446, 4071.837126514936, 3530.7351948603828, 3909.061825161969, 4072.1448495815916, 4096.262672718909, 4096.836126554934, 3710.513955649708, 3773.283076215524, 4032.516098068236, 4032.931273212601, 4054.878353649395, 3329.517220003098, 3995.3807159889975, 4041.858534951292, 4041.939370909424, 4043.777592232426, 3005.9779674910415, 3558.3594952908466, 3570.036090255642, 3571.4642803579413, 3577.3203091412547, 4457.576530229623, 4937.679050861682, 5119.56483698886, 5198.064348417283, 5223.843284701465, 4407.405000324947, 4423.579759922803, 4602.585767280961, 4630.282306242504, 4651.929891198418, 4599.333096700993, 4839.3466881970835, 5094.7197904115255, 5495.340559132929, 5612.915806262909, 3532.6114127445962, 3877.856032470428, 3904.9609503905167, 3937.76373417593, 3949.447077409165, 4636.976815115937, 5084.339035925306, 5502.669839809584, 5806.361300256968, 5834.474897259267, 3925.8822235332414, 3936.389859571771, 3939.901502462404, 3942.9605703943166, 3950.762954222756, 3322.7175690066133, 3535.7701749386206, 3891.2412079644196, 4044.757314561136, 4054.614811592894, 4402.911941761146, 4618.445786505641, 4658.720476771405, 4662.7668616569035, 4672.76636168197, 4838.467768545457, 5155.97422012895, 5279.39286982002, 5551.694656793915, 5596.0, 1355.3765267976842, 1357.0, 1375.848656647768, 1378.9172649641055, 1380.6617378742274, 849.7408290471466, 850.9958249265926, 853.4026181672854, 871.5903525343078, 944.3531181140941, 4371.366151907955, 5139.3040296702, 5144.768485418165, 5153.871153221199, 5156.9742151289, 4503.437070366204, 4733.786979585927, 4761.500042495557, 5409.945900541023, 5626.662400255916, 4238.639715624145, 4886.286422319063, 5052.671576347526, 5065.924011139836, 5093.083245015932, 2439.0, 2948.292409821627, 2953.18787333485, 2953.793234473609, 2958.2308599764215, 5124.256982737482, 5724.4561766632105, 5734.741936612779, 5743.0, 5759.913601295984, 5013.649044566845, 5273.0, 5506.449355064461, 5630.915536266959, 5752.424757524298, 4796.834089500912, 5440.510354068074, 5726.341470730919, 5740.019693205837, 5765.60727298175, 4468.927457410133, 4896.510348965217, 5250.97374513125, 5571.1086226203615, 5586.972065139648, 4763.428388593391, 5082.186850103965, 5491.807786727445, 5540.362858271189, 5550.167474878801, 5737.287373772612, 5748.166515855261, 5769.278840144982, 5774.393688662798, 5806.593538452268, 3296.093574267096, 3940.2513522430645, 3941.5270167580074, 3954.406838974177, 3959.287328280937, 4983.925241121458, 5536.252605898152, 5546.639468434539, 5568.638038527482, 5569.79992902793, 4915.485427919055, 4916.778744956557, 5442.591805614674, 5454.181872719124, 5464.781408743765, 4650.767461626972, 5079.77141028662, 5199.012187684328, 5470.343558772973, 5472.288602481652, 4684.0, 5329.360476742816, 5339.492748188916, 5355.785768569213, 5368.516833485005, 4960.50394960501, 5320.494553017457, 5322.680639161663, 5480.588955828249, 5504.669719816824, 4534.478534968521, 5410.621256512007, 5425.701586412785, 5431.945680543302, 5436.7281635918835], "value": 3997.8133750540246, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [793.6031984007996, 797.3979645367748, 808.8544062068826, 833.2833762963846, 848.5163456829607, 14927.626809329759, 15865.920670396632, 16220.83779162208, 16229.594260143487, 16820.990740555575, 14874.107553546795, 15312.0, 15323.693526129471, 15342.84657153428, 15483.225838708047, 716.9569825810441, 903.0969030969047, 912.0423555266943, 913.6025828764475, 916.2349438219062, 749.4828568287891, 895.9910400895973, 927.5455027036771, 932.4684929590136, 939.9530023498831, 969.5927710361665, 970.315927271272, 971.0, 971.1502435369052, 971.8299297622923, 758.4463341760546, 963.9469829159393, 964.9517524123765, 965.5365424596198, 965.9758506037406, 574.4628772098101, 575.7610591604479, 576.1703147467675, 580.118220305138, 696.1680791454214, 13650.249236292, 15690.686186276325, 15701.136437495934, 15708.057516549045, 15717.685646287011, 14433.566992990227, 14593.0, 14727.410903563845, 14776.187309697962, 14974.775378369333, 13355.332233388292, 13382.531611393651, 13600.387982540711, 13831.585052448443, 13831.654208644764, 4538.568835960578, 4638.976805115953, 4776.140294746944, 4831.541003604652, 4832.73419961902, 97681.0, 121182.78817211803, 123425.38287308595, 124381.37809310986, 124803.0, 11388.608392237556, 11546.191766576427, 11768.0, 11901.464434100486, 12173.452194651261, 23534.411639709146, 23851.52296954086, 23874.40313992164, 23898.64152037721, 23909.16317928899, 7594.696212151563, 7753.1083925348175, 7771.805704857311, 7934.841303173905, 7987.600619968978, 4462.3752674625575, 4530.456345238593, 4533.795979180944, 4653.650976176733, 4704.717716936994, 3365.2764655599162, 3502.9299414011575, 3516.507688923553, 3521.0669172669263, 3543.3976224041976, 632.0866348127005, 644.2365796531126, 649.2987573420788, 661.6460193796296, 665.8035879415584, 564.9661020338793, 565.2877374508067, 585.1544518171202, 628.3465196195997, 681.9454443644496, 7242.674079666428, 10523.10553602937, 10574.577016919391, 10575.418351990715, 10757.677269681923, 625.5371025441232, 683.4498228925762, 689.2797027106685, 703.43022152057, 714.17156098925, 4278.379634952915, 4746.738929358849, 4749.382580264543, 4840.394950631171, 4884.413870335582, 88334.0, 101268.0, 141373.5862641381, 152153.0, 158523.0, 130555.0, 131891.0, 132304.01543976847, 132623.0, 133904.0, 68667.0, 73972.0, 94784.52607737055, 109774.0, 110145.44927275312, 9134.31492638068, 9143.26853851677, 9149.22231610294, 9152.908470915205, 9372.062793720574, 60779.0, 60981.39018609757, 61110.0, 63191.052134218946, 66558.0, 90182.1963560712, 92515.53742231514, 95231.04768952222, 95339.0, 98316.52525212271, 10715.303505272132, 11989.820152697543, 12083.39583020828, 12128.393580320771, 12372.76577953187, 11349.013922563683, 13058.673533161367, 13357.931365490931, 13456.86543134556, 13987.23070231147, 9376.390534615228, 12604.621861344174, 12902.225866448043, 12988.740225195632, 13131.868681313064, 9755.21225266684, 13851.861481385056, 14082.258748410191, 14184.219867906952, 14728.926355368154, 13407.32963351848, 13961.371738272006, 13969.650758730706, 13994.650133746738, 14231.288435578386, 10910.836337454786, 11339.433028348714, 11464.4267786612, 11565.884341156481, 11840.0, 9589.616415343307, 10940.398278094463, 11325.546978121112, 11359.54561817518, 11682.94158529202], "value": 7926.47261569086, "unit": "score"}], "name": "dromaeo_css", "value": 7363.980839452414, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 111.0, 112.0, 112.0, 113.0, 114.0, 114.0, 112.0, 112.0, 119.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 115.0, 107.0, 160.0, 108.0, 108.0, 128.0, 106.0, 141.0, 107.0], "value": 122.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 187.0, 182.0, 182.0, 180.0, 183.0, 185.0, 184.0, 184.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 58.0, 103.0, 59.0, 58.0, 66.0, 70.0, 84.0, 56.0], "value": 69.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 68.0, 92.0, 71.0, 70.0, 68.0, 97.0, 78.0, 98.0, 135.0], "value": 86.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 91.0, 87.0, 90.0, 86.0, 90.0, 88.0, 135.0, 86.0], "value": 92.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 86.0, 87.0, 87.0, 85.0, 86.0, 85.0, 86.0, 90.0], "value": 86.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 52.0, 53.0, 55.0, 55.0, 54.0, 52.0, 52.0, 53.0], "value": 53.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 76.0, 67.0, 78.0, 72.0, 73.0, 72.0, 72.0, 72.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 76.0, 83.0, 82.0, 82.0, 85.0, 85.0, 84.0, 83.0, 81.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 137.0, 133.0, 132.0, 136.0, 131.0, 132.0, 177.0, 136.0], "value": 138.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 162.0, 163.0, 163.0, 160.0, 162.0, 159.0, 157.0, 222.0], "value": 167.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 66.0, 67.0, 69.0, 64.0, 65.0, 66.0, 65.0, 65.0], "value": 66.0, "unit": "score"}], "name": "kraken", "value": 1425.9, "alertThreshold": 2.0}]} 19:27:52 INFO - Return code: 0 19:27:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:27:52 INFO - # TBPL SUCCESS # 19:27:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:27:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:27:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:27:52 INFO - 2016-07-21 19:27:52.827 screenresolution[1672:10047] starting screenresolution argv=screenresolution get 19:27:52 INFO - 2016-07-21 19:27:52.830 screenresolution[1672:10047] Display 0: 1600x1200x32@60 19:27:52 INFO - 2016-07-21 19:27:52.835 screenresolution[1673:10050] starting screenresolution argv=screenresolution list 19:27:52 INFO - Available Modes on Display 0 19:27:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:27:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:27:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:27:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:27:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:27:53 INFO - Intel Iris: 19:27:53 INFO - Chipset Model: Intel Iris 19:27:53 INFO - Type: GPU 19:27:53 INFO - Bus: Built-In 19:27:53 INFO - VRAM (Dynamic, Max): 1536 MB 19:27:53 INFO - Vendor: Intel (0x8086) 19:27:53 INFO - Device ID: 0x0a2e 19:27:53 INFO - Revision ID: 0x0009 19:27:53 INFO - Displays: 19:27:53 INFO - Display: 19:27:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:27:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:27:53 INFO - Display Serial Number: 1600x1200 60 19:27:53 INFO - Main Display: Yes 19:27:53 INFO - Mirror: Off 19:27:53 INFO - Online: Yes 19:27:53 INFO - Rotation: Supported 19:27:53 INFO - Return code: 0 19:27:53 INFO - Running post-action listener: _resource_record_post_action 19:27:53 INFO - [mozharness: 2016-07-22 02:27:53.068078Z] Finished run-tests step (success) 19:27:53 INFO - Running post-run listener: _resource_record_post_run 19:27:53 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 38669824; Write bytes: 704285696; Read time: 370; Write time: 1799 19:27:53 INFO - TinderboxPrint: CPU usage
53.3% 19:27:53 INFO - TinderboxPrint: I/O read bytes / time
38,669,824 / 370 19:27:53 INFO - TinderboxPrint: I/O write bytes / time
704,285,696 / 1,799 19:27:53 INFO - TinderboxPrint: CPU idle
2,263.4 (46.7%) 19:27:53 INFO - TinderboxPrint: CPU system
965.2 (19.9%) 19:27:53 INFO - TinderboxPrint: CPU user
1,617.0 (33.4%) 19:27:53 INFO - TinderboxPrint: Swap in / out
450,207,744 / 0 19:27:53 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 393146880; Write bytes: 386561536; Read time: 29388; Write time: 669 19:27:53 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 37740032; Write bytes: 291679744; Read time: 356; Write time: 1068 19:27:53 INFO - Running post-run listener: _upload_blobber_files 19:27:53 INFO - Blob upload gear active. 19:27:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:27:53 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 19:27:53 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'] 19:27:53 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 19:27:53 INFO - (blobuploader) - INFO - Open directory for files ... 19:27:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:27:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:27:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:27:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:27:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:27:55 INFO - (blobuploader) - INFO - Done attempting. 19:27:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:27:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:27:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:27:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:27:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:27:56 INFO - (blobuploader) - INFO - Done attempting. 19:27:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:27:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:27:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:28:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:28:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:28:00 INFO - (blobuploader) - INFO - Done attempting. 19:28:00 INFO - (blobuploader) - INFO - Iteration through files over. 19:28:00 INFO - Return code: 0 19:28:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:28:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:28:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dfe4d820ce46ad036b0ae8ee144b200c1b2f80aebc76a29d141220a29b2442dd2581653749af1c4f2c31749936f9a561ec7dd2c2f14b8512f40beaf8476a5f72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/04714b0b412752c121e8f271e04d200de069d2245a039c26ac96acaee59d706bc5a084846062be03fcc17df21a8a7e422a19330481b59357d83957cf6ee75f53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/302712dbf27ba782ca5ecab876a9e5aace924627d800cd948bf0096920daffe7b56339112c62e00f5e0332ba15633b73676028daa095cc9190c4b5af44e565be"} 19:28:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:28:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:28:00 INFO - Contents: 19:28:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dfe4d820ce46ad036b0ae8ee144b200c1b2f80aebc76a29d141220a29b2442dd2581653749af1c4f2c31749936f9a561ec7dd2c2f14b8512f40beaf8476a5f72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/04714b0b412752c121e8f271e04d200de069d2245a039c26ac96acaee59d706bc5a084846062be03fcc17df21a8a7e422a19330481b59357d83957cf6ee75f53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/302712dbf27ba782ca5ecab876a9e5aace924627d800cd948bf0096920daffe7b56339112c62e00f5e0332ba15633b73676028daa095cc9190c4b5af44e565be"} 19:28:00 INFO - Running post-run listener: copy_logs_to_upload_dir 19:28:00 INFO - Copying logs to upload dir... 19:28:00 INFO - mkdir: /builds/slave/test/build/upload/logs 19:28:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.134562 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-21 19:28:00.407979) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 19:28:00.411582) ========= 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.yH2KSPLcXy/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.9N1TXglk0O/Listeners TMPDIR=/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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/dfe4d820ce46ad036b0ae8ee144b200c1b2f80aebc76a29d141220a29b2442dd2581653749af1c4f2c31749936f9a561ec7dd2c2f14b8512f40beaf8476a5f72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/04714b0b412752c121e8f271e04d200de069d2245a039c26ac96acaee59d706bc5a084846062be03fcc17df21a8a7e422a19330481b59357d83957cf6ee75f53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/302712dbf27ba782ca5ecab876a9e5aace924627d800cd948bf0096920daffe7b56339112c62e00f5e0332ba15633b73676028daa095cc9190c4b5af44e565be"} build_url:https://queue.taskcluster.net/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011135 build_url: 'https://queue.taskcluster.net/v1/task/GfeHfm2qTj23C1ZIrXGf2A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dfe4d820ce46ad036b0ae8ee144b200c1b2f80aebc76a29d141220a29b2442dd2581653749af1c4f2c31749936f9a561ec7dd2c2f14b8512f40beaf8476a5f72", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/04714b0b412752c121e8f271e04d200de069d2245a039c26ac96acaee59d706bc5a084846062be03fcc17df21a8a7e422a19330481b59357d83957cf6ee75f53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/302712dbf27ba782ca5ecab876a9e5aace924627d800cd948bf0096920daffe7b56339112c62e00f5e0332ba15633b73676028daa095cc9190c4b5af44e565be"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 19:28:00.444363) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 19:28:00.444691) ========= 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.yH2KSPLcXy/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.9N1TXglk0O/Listeners TMPDIR=/var/folders/n6/d_jx6m8x0qs8z3xjt5fbd5cm00000w/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.005265 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 19:28:00.493262) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 19:28:00.493575) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 19:28:00.493926) ========= ========= Total master_lag: 0.20 =========