builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0370 starttime: 1472227541.83 results: success (0) buildid: 20160826063820 builduid: 849d736893ee4dd08e0dded7c8ab111a revision: a65b35c8e5b17c2585968974aef1da67a8c56642 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 09:05:41.826154) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 09:05:41.826589) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 09:05:41.826863) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 09:05:41.851646) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 09:05:41.852069) ========= 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.39CNihEE1A/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.tLsJcOGIHI/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-26 09:05:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 57.0M=0s 2016-08-26 09:05:42 (57.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.555718 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 09:05:42.432686) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 09:05:42.432985) ========= 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.39CNihEE1A/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.tLsJcOGIHI/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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.025661 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 09:05:42.481275) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 09:05:42.481575) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a65b35c8e5b17c2585968974aef1da67a8c56642 --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 a65b35c8e5b17c2585968974aef1da67a8c56642 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.39CNihEE1A/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.tLsJcOGIHI/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-26 09:05:42,569 truncating revision to first 12 chars 2016-08-26 09:05:42,569 Setting DEBUG logging. 2016-08-26 09:05:42,569 attempt 1/10 2016-08-26 09:05:42,569 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a65b35c8e5b1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 09:05:43,597 unpacking tar archive at: fx-team-a65b35c8e5b1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.286894 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 09:05:43.824762) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 09:05:43.825040) ========= script_repo_revision: a65b35c8e5b17c2585968974aef1da67a8c56642 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 09:05:43.825381) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 09:05:43.825643) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 09:05:43.858830) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-26 09:05:43.859101) ========= /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.39CNihEE1A/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.tLsJcOGIHI/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 09:05:44 INFO - MultiFileLogger online at 20160826 09:05:44 in /builds/slave/test 09:05:44 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 09:05:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:05:44 INFO - {'append_to_log': False, 09:05:44 INFO - 'base_work_dir': '/builds/slave/test', 09:05:44 INFO - 'blob_upload_branch': 'Fx-Team', 09:05:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:05:44 INFO - 'branch': 'Fx-Team', 09:05:44 INFO - 'buildbot_json_path': 'buildprops.json', 09:05:44 INFO - 'config_files': ('talos/mac_config.py',), 09:05:44 INFO - 'default_actions': ('clobber', 09:05:44 INFO - 'read-buildbot-config', 09:05:44 INFO - 'download-and-extract', 09:05:44 INFO - 'populate-webroot', 09:05:44 INFO - 'create-virtualenv', 09:05:44 INFO - 'install', 09:05:44 INFO - 'run-tests'), 09:05:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:05:44 INFO - 'download_minidump_stackwalk': True, 09:05:44 INFO - 'download_symbols': 'ondemand', 09:05:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:05:44 INFO - 'tooltool.py': '/tools/tooltool.py', 09:05:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:05:44 INFO - '/tools/misc-python/virtualenv.py')}, 09:05:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:05:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:05:44 INFO - 'installer_path': 'installer.exe', 09:05:44 INFO - 'log_level': 'info', 09:05:44 INFO - 'log_name': 'talos', 09:05:44 INFO - 'log_to_console': True, 09:05:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:05:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:05:44 INFO - 'opt_config_files': (), 09:05:44 INFO - 'pip_index': False, 09:05:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:05:44 INFO - 'cmd': ('bash', 09:05:44 INFO - '-c', 09:05:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:05:44 INFO - 'enabled': True, 09:05:44 INFO - 'halt_on_failure': False, 09:05:44 INFO - 'name': 'check_screen_resolution'},), 09:05:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:05:44 INFO - 'cmd': ('bash', 09:05:44 INFO - '-c', 09:05:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:05:44 INFO - 'enabled': True, 09:05:44 INFO - 'halt_on_failure': False, 09:05:44 INFO - 'name': 'check_screen_resolution'},), 09:05:44 INFO - 'run_cmd_checks_enabled': True, 09:05:44 INFO - 'sps_profile': False, 09:05:44 INFO - 'sps_profile_interval': 0, 09:05:44 INFO - 'suite': 'dromaeojs', 09:05:44 INFO - 'system_bits': '32', 09:05:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:05:44 INFO - 'title': 't-yosemite-r7-0370', 09:05:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:05:44 INFO - 'use_talos_json': True, 09:05:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:05:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:05:44 INFO - 'work_dir': 'build'} 09:05:44 INFO - [mozharness: 2016-08-26 16:05:44.060429Z] Running clobber step. 09:05:44 INFO - Running pre-action listener: _resource_record_pre_action 09:05:44 INFO - Running main action method: clobber 09:05:44 INFO - rmtree: /builds/slave/test/build 09:05:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:05:46 INFO - Running post-action listener: _resource_record_post_action 09:05:46 INFO - [mozharness: 2016-08-26 16:05:46.048333Z] Finished clobber step (success) 09:05:46 INFO - [mozharness: 2016-08-26 16:05:46.048563Z] Running read-buildbot-config step. 09:05:46 INFO - Running pre-action listener: _resource_record_pre_action 09:05:46 INFO - Running main action method: read_buildbot_config 09:05:46 INFO - Using buildbot properties: 09:05:46 INFO - { 09:05:46 INFO - "project": "", 09:05:46 INFO - "product": "firefox", 09:05:46 INFO - "script_repo_revision": "production", 09:05:46 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:05:46 INFO - "repository": "", 09:05:46 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:05:46 INFO - "buildid": "20160826063820", 09:05:46 INFO - "pgo_build": "False", 09:05:46 INFO - "basedir": "/builds/slave/test", 09:05:46 INFO - "buildnumber": 124, 09:05:46 INFO - "slavename": "t-yosemite-r7-0370", 09:05:46 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:05:46 INFO - "platform": "yosemite_r7", 09:05:46 INFO - "scheduler": "tests-fx-team-macosx64-talos", 09:05:46 INFO - "branch": "fx-team", 09:05:46 INFO - "repo_path": "integration/fx-team", 09:05:46 INFO - "revision": "a65b35c8e5b17c2585968974aef1da67a8c56642", 09:05:46 INFO - "stage_platform": "macosx64", 09:05:46 INFO - "builduid": "849d736893ee4dd08e0dded7c8ab111a", 09:05:46 INFO - "slavebuilddir": "test" 09:05:46 INFO - } 09:05:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:05:46 INFO - Running post-action listener: _resource_record_post_action 09:05:46 INFO - [mozharness: 2016-08-26 16:05:46.051667Z] Finished read-buildbot-config step (success) 09:05:46 INFO - [mozharness: 2016-08-26 16:05:46.051819Z] Running download-and-extract step. 09:05:46 INFO - Running pre-action listener: _resource_record_pre_action 09:05:46 INFO - Running main action method: download_and_extract 09:05:46 INFO - mkdir: /builds/slave/test/build/tests 09:05:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:46 INFO - trying https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:05:46 INFO - Downloading https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:05:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 09:05:47 INFO - Downloaded 1199 bytes. 09:05:47 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:05:47 INFO - Using the following test package requirements: 09:05:47 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:05:47 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:05:47 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:05:47 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:05:47 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:05:47 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:05:47 INFO - u'jsshell-mac.zip'], 09:05:47 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:05:47 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:05:47 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:05:47 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:05:47 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:05:47 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:05:47 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:05:47 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:05:47 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:05:47 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:05:47 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:05:47 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:05:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:47 INFO - trying https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:05:47 INFO - Downloading https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:05:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:05:48 INFO - Downloaded 16368057 bytes. 09:05:48 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:05:55 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:05:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:55 INFO - trying https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:05:55 INFO - Downloading https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:05:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:05:56 INFO - Downloaded 16368057 bytes. 09:05:56 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:03 INFO - trying https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:06:03 INFO - Downloading https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:06:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:06:04 INFO - Downloaded 14413998 bytes. 09:06:04 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 09:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:04 INFO - trying https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:06:04 INFO - Downloading https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:06:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 09:06:08 INFO - Downloaded 111605676 bytes. 09:06:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:06:08 INFO - mkdir: /builds/slave/test/properties 09:06:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:06:08 INFO - Writing to file /builds/slave/test/properties/build_url 09:06:08 INFO - Contents: 09:06:08 INFO - build_url:https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:06:09 INFO - Running post-action listener: _resource_record_post_action 09:06:09 INFO - Running post-action listener: set_extra_try_arguments 09:06:09 INFO - [mozharness: 2016-08-26 16:06:09.436307Z] Finished download-and-extract step (success) 09:06:09 INFO - [mozharness: 2016-08-26 16:06:09.436429Z] Running populate-webroot step. 09:06:09 INFO - Running pre-action listener: _resource_record_pre_action 09:06:09 INFO - Running main action method: populate_webroot 09:06:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:06:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:06:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:06:09 INFO - '--tppagecycles', 09:06:09 INFO - '7'], 09:06:09 INFO - 'tests': ['tsvgm']}}, 09:06:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:06:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:06:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:06:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:06:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:06:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:06:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:09 INFO - 'talos_options': ['--disable-e10s'], 09:06:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:06:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:06:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:09 INFO - 'talos_options': ['--disable-e10s'], 09:06:09 INFO - 'tests': ['damp', 'tps']}, 09:06:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:09 INFO - 'tests': ['damp', 'tps']}, 09:06:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:06:09 INFO - 'tests': ['dromaeo_dom']}, 09:06:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:06:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:06:09 INFO - 'tests': ['basic_compositor_video']}, 09:06:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:06:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:06:09 INFO - 'tests': ['a11yr', 09:06:09 INFO - 'ts_paint', 09:06:09 INFO - 'tpaint', 09:06:09 INFO - 'sessionrestore', 09:06:09 INFO - 'sessionrestore_no_auto_restore', 09:06:09 INFO - 'tabpaint']}, 09:06:09 INFO - 'other-e10s': {'tests': ['a11yr', 09:06:09 INFO - 'ts_paint', 09:06:09 INFO - 'tpaint', 09:06:09 INFO - 'sessionrestore', 09:06:09 INFO - 'sessionrestore_no_auto_restore', 09:06:09 INFO - 'tabpaint']}, 09:06:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:06:09 INFO - 'ts_paint', 09:06:09 INFO - 'tpaint', 09:06:09 INFO - 'sessionrestore', 09:06:09 INFO - 'sessionrestore_no_auto_restore', 09:06:09 INFO - 'tabpaint']}, 09:06:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:06:09 INFO - 'ts_paint', 09:06:09 INFO - 'tpaint', 09:06:09 INFO - 'sessionrestore', 09:06:09 INFO - 'sessionrestore_no_auto_restore', 09:06:09 INFO - 'tabpaint']}, 09:06:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:06:09 INFO - 'tests': ['a11yr', 09:06:09 INFO - 'ts_paint', 09:06:09 INFO - 'tpaint', 09:06:09 INFO - 'sessionrestore', 09:06:09 INFO - 'sessionrestore_no_auto_restore', 09:06:09 INFO - 'tabpaint']}, 09:06:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:06:09 INFO - 'tests': ['a11yr', 09:06:09 INFO - 'ts_paint', 09:06:09 INFO - 'tpaint', 09:06:09 INFO - 'sessionrestore', 09:06:09 INFO - 'sessionrestore_no_auto_restore', 09:06:09 INFO - 'tabpaint']}, 09:06:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:06:09 INFO - 'tests': ['tsvgx', 09:06:09 INFO - 'tsvgr_opacity', 09:06:09 INFO - 'tart', 09:06:09 INFO - 'tscrollx', 09:06:09 INFO - 'cart']}, 09:06:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:06:09 INFO - 'tsvgr_opacity', 09:06:09 INFO - 'tart', 09:06:09 INFO - 'tscrollx', 09:06:09 INFO - 'cart']}, 09:06:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:09 INFO - 'talos_options': ['--disable-e10s'], 09:06:09 INFO - 'tests': ['tp5o']}, 09:06:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:09 INFO - 'tests': ['tp5o']}, 09:06:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:09 INFO - 'talos_options': ['--disable-e10s', 09:06:09 INFO - '--xperf_path', 09:06:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:06:09 INFO - 'tests': ['tp5n']}, 09:06:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:09 INFO - 'talos_options': ['--xperf_path', 09:06:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:06:09 INFO - 'tests': ['tp5n']}}, 09:06:09 INFO - 'talos.zip': {'path': '', 09:06:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:06:09 INFO - Running post-action listener: _resource_record_post_action 09:06:09 INFO - [mozharness: 2016-08-26 16:06:09.455435Z] Finished populate-webroot step (success) 09:06:09 INFO - [mozharness: 2016-08-26 16:06:09.455565Z] Running create-virtualenv step. 09:06:09 INFO - Running pre-action listener: _resource_record_pre_action 09:06:09 INFO - Running main action method: create_virtualenv 09:06:09 INFO - Creating virtualenv /builds/slave/test/build/venv 09:06:09 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 09:06:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:06:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:06:09 INFO - Using real prefix '/tools/python27' 09:06:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:06:10 INFO - Installing distribute.............................................................................................................................................................................................done. 09:06:13 INFO - Installing pip.................done. 09:06:13 INFO - Return code: 0 09:06:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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 0x10d536a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93a84869b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d49ad30>, '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 0x10d1ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d492b20>, '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.39CNihEE1A/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.tLsJcOGIHI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 09:06: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 09:06: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 09:06:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CNihEE1A/Render', 09:06:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:13 INFO - 'HOME': '/Users/cltbld', 09:06:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:13 INFO - 'LOGNAME': 'cltbld', 09:06:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:13 INFO - 'MOZ_NO_REMOTE': '1', 09:06:13 INFO - 'NO_EM_RESTART': '1', 09:06:13 INFO - 'PAGER': '/bin/cat', 09:06:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:13 INFO - 'PWD': '/builds/slave/test', 09:06:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:13 INFO - 'SHELL': '/bin/bash', 09:06:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tLsJcOGIHI/Listeners', 09:06:13 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 09:06:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:13 INFO - 'USER': 'cltbld', 09:06:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:13 INFO - 'XPC_FLAGS': '0x0', 09:06:13 INFO - 'XPC_SERVICE_NAME': '0', 09:06:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:13 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 09:06:13 INFO - Cleaning up... 09:06:13 INFO - Return code: 0 09:06:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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', '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 0x10d536a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93a84869b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d49ad30>, '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 0x10d1ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d492b20>, '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.39CNihEE1A/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.tLsJcOGIHI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 09:06: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:06: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 psutil>=3.1.1 09:06:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CNihEE1A/Render', 09:06:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:13 INFO - 'HOME': '/Users/cltbld', 09:06:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:13 INFO - 'LOGNAME': 'cltbld', 09:06:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:13 INFO - 'MOZ_NO_REMOTE': '1', 09:06:13 INFO - 'NO_EM_RESTART': '1', 09:06:13 INFO - 'PAGER': '/bin/cat', 09:06:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:13 INFO - 'PWD': '/builds/slave/test', 09:06:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:13 INFO - 'SHELL': '/bin/bash', 09:06:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tLsJcOGIHI/Listeners', 09:06:13 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 09:06:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:13 INFO - 'USER': 'cltbld', 09:06:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:13 INFO - 'XPC_FLAGS': '0x0', 09:06:13 INFO - 'XPC_SERVICE_NAME': '0', 09:06:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:13 INFO - Downloading/unpacking psutil>=3.1.1 09:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:06:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:06:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:06:17 INFO - Installing collected packages: psutil 09:06:17 INFO - Running setup.py install for psutil 09:06:17 INFO - building 'psutil._psutil_osx' extension 09:06: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 09:06: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:06: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:06:17 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 09:06:18 INFO - building 'psutil._psutil_posix' extension 09:06: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 09:06:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:06:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:06:18 INFO - ^ 09:06:18 INFO - 1 warning generated. 09:06: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 09:06:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:06:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:06:18 INFO - Successfully installed psutil 09:06:18 INFO - Cleaning up... 09:06:18 INFO - Return code: 0 09:06:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:06:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d536a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93a84869b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d49ad30>, '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 0x10d1ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d492b20>, '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.39CNihEE1A/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.tLsJcOGIHI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 09:06:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:06:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:06:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CNihEE1A/Render', 09:06:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:18 INFO - 'HOME': '/Users/cltbld', 09:06:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:18 INFO - 'LOGNAME': 'cltbld', 09:06:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:18 INFO - 'MOZ_NO_REMOTE': '1', 09:06:18 INFO - 'NO_EM_RESTART': '1', 09:06:18 INFO - 'PAGER': '/bin/cat', 09:06:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:18 INFO - 'PWD': '/builds/slave/test', 09:06:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:18 INFO - 'SHELL': '/bin/bash', 09:06:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tLsJcOGIHI/Listeners', 09:06:18 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 09:06:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:18 INFO - 'USER': 'cltbld', 09:06:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:18 INFO - 'XPC_FLAGS': '0x0', 09:06:18 INFO - 'XPC_SERVICE_NAME': '0', 09:06:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:06:18 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 09:06:18 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 09:06:18 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 09:06:18 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 09:06:18 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 09:06:18 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 09:06:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:06:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:06:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:06:21 INFO - Installing collected packages: mozsystemmonitor 09:06:21 INFO - Running setup.py install for mozsystemmonitor 09:06:21 INFO - Successfully installed mozsystemmonitor 09:06:21 INFO - Cleaning up... 09:06:21 INFO - Return code: 0 09:06:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:06:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:21 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 0x10d536a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93a84869b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d49ad30>, '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 0x10d1ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d492b20>, '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.39CNihEE1A/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.tLsJcOGIHI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 09:06:21 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 09:06:21 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 09:06:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CNihEE1A/Render', 09:06:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:21 INFO - 'HOME': '/Users/cltbld', 09:06:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:21 INFO - 'LOGNAME': 'cltbld', 09:06:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:21 INFO - 'MOZ_NO_REMOTE': '1', 09:06:21 INFO - 'NO_EM_RESTART': '1', 09:06:21 INFO - 'PAGER': '/bin/cat', 09:06:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:21 INFO - 'PWD': '/builds/slave/test', 09:06:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:21 INFO - 'SHELL': '/bin/bash', 09:06:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tLsJcOGIHI/Listeners', 09:06:21 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 09:06:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:21 INFO - 'USER': 'cltbld', 09:06:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:21 INFO - 'XPC_FLAGS': '0x0', 09:06:21 INFO - 'XPC_SERVICE_NAME': '0', 09:06:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:21 INFO - Downloading/unpacking jsonschema==2.5.1 09:06:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:06:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06:24 INFO - Downloading functools32-3.2.3-2.tar.gz 09:06:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:06:24 INFO - warning: no files found matching '*.txt' 09:06:24 INFO - no previously-included directories found matching 'build' 09:06:24 INFO - no previously-included directories found matching 'dist' 09:06:24 INFO - no previously-included directories found matching '.git*' 09:06:24 INFO - Installing collected packages: jsonschema, functools32 09:06:24 INFO - Running setup.py install for functools32 09:06:24 INFO - warning: no files found matching '*.txt' 09:06:24 INFO - no previously-included directories found matching 'build' 09:06:24 INFO - no previously-included directories found matching 'dist' 09:06:24 INFO - no previously-included directories found matching '.git*' 09:06:24 INFO - Successfully installed jsonschema functools32 09:06:24 INFO - Cleaning up... 09:06:24 INFO - Return code: 0 09:06:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:06:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d536a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93a84869b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d49ad30>, '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 0x10d1ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d492b20>, '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.39CNihEE1A/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.tLsJcOGIHI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 09:06:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:06:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:06:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CNihEE1A/Render', 09:06:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:24 INFO - 'HOME': '/Users/cltbld', 09:06:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:24 INFO - 'LOGNAME': 'cltbld', 09:06:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:24 INFO - 'MOZ_NO_REMOTE': '1', 09:06:24 INFO - 'NO_EM_RESTART': '1', 09:06:24 INFO - 'PAGER': '/bin/cat', 09:06:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:24 INFO - 'PWD': '/builds/slave/test', 09:06:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:24 INFO - 'SHELL': '/bin/bash', 09:06:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tLsJcOGIHI/Listeners', 09:06:24 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 09:06:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:24 INFO - 'USER': 'cltbld', 09:06:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:24 INFO - 'XPC_FLAGS': '0x0', 09:06:24 INFO - 'XPC_SERVICE_NAME': '0', 09:06:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:06:25 INFO - Cleaning up... 09:06:25 INFO - Return code: 0 09:06:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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 0x10d536a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93a84869b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d49ad30>, '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 0x10d1ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d492b20>, '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.39CNihEE1A/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.tLsJcOGIHI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 09:06: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 09:06: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 09:06:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CNihEE1A/Render', 09:06:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:25 INFO - 'HOME': '/Users/cltbld', 09:06:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:25 INFO - 'LOGNAME': 'cltbld', 09:06:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:25 INFO - 'MOZ_NO_REMOTE': '1', 09:06:25 INFO - 'NO_EM_RESTART': '1', 09:06:25 INFO - 'PAGER': '/bin/cat', 09:06:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:25 INFO - 'PWD': '/builds/slave/test', 09:06:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:25 INFO - 'SHELL': '/bin/bash', 09:06:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tLsJcOGIHI/Listeners', 09:06:25 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 09:06:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:25 INFO - 'USER': 'cltbld', 09:06:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:25 INFO - 'XPC_FLAGS': '0x0', 09:06:25 INFO - 'XPC_SERVICE_NAME': '0', 09:06:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:25 INFO - Downloading/unpacking blobuploader==1.2.4 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06:27 INFO - Downloading blobuploader-1.2.4.tar.gz 09:06:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:06:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:06:27 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 09:06:27 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 09:06:27 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 09:06:27 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 09:06:27 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 09:06:27 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 09:06:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:06:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06: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 09:06:28 INFO - Downloading docopt-0.6.1.tar.gz 09:06:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:06:29 INFO - Installing collected packages: blobuploader, requests, docopt 09:06:29 INFO - Running setup.py install for blobuploader 09:06:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:06:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:06:29 INFO - Running setup.py install for requests 09:06:29 INFO - Running setup.py install for docopt 09:06:29 INFO - Successfully installed blobuploader requests docopt 09:06:29 INFO - Cleaning up... 09:06:29 INFO - Return code: 0 09:06:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:06:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:29 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 0x10d536a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93a84869b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d49ad30>, '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 0x10d1ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d492b20>, '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.39CNihEE1A/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.tLsJcOGIHI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 09:06:29 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 09:06:29 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 09:06:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CNihEE1A/Render', 09:06:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:29 INFO - 'HOME': '/Users/cltbld', 09:06:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:29 INFO - 'LOGNAME': 'cltbld', 09:06:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:29 INFO - 'MOZ_NO_REMOTE': '1', 09:06:29 INFO - 'NO_EM_RESTART': '1', 09:06:29 INFO - 'PAGER': '/bin/cat', 09:06:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:29 INFO - 'PWD': '/builds/slave/test', 09:06:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:29 INFO - 'SHELL': '/bin/bash', 09:06:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tLsJcOGIHI/Listeners', 09:06:29 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 09:06:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:29 INFO - 'USER': 'cltbld', 09:06:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:29 INFO - 'XPC_FLAGS': '0x0', 09:06:29 INFO - 'XPC_SERVICE_NAME': '0', 09:06:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:06:30 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-JImIUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:06:30 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-w3EARS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:06:30 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-7wJcLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:06:30 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-ntXFr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:06:30 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-x12Moa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:06:30 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-MKmx6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:06:30 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-2otFLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:06:30 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-CiCpmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:06:30 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-nYVkVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:06:31 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-dwmL1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:06:31 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-a5ceFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:06:31 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-2zM56G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:06:31 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-36IERi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:06:31 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-qHhapf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:31 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-kaTEI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:06:31 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-mTTKsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:06:31 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-ZrkjD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:06:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:06:31 INFO - Running setup.py install for manifestparser 09:06:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:06:32 INFO - Running setup.py install for mozcrash 09:06:32 INFO - Running setup.py install for mozdebug 09:06:32 INFO - Running setup.py install for mozdevice 09:06:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:06:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:06:32 INFO - Running setup.py install for mozfile 09:06:32 INFO - Running setup.py install for mozhttpd 09:06:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:06:32 INFO - Running setup.py install for mozinfo 09:06:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:06:33 INFO - Running setup.py install for mozInstall 09:06:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:06:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:06:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:06:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:06:33 INFO - Running setup.py install for mozleak 09:06:33 INFO - Running setup.py install for mozlog 09:06:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:06:33 INFO - Running setup.py install for moznetwork 09:06:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:06:33 INFO - Running setup.py install for mozprocess 09:06:33 INFO - Running setup.py install for mozprofile 09:06:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:06:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:06:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:06:33 INFO - Running setup.py install for mozrunner 09:06:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:06:34 INFO - Running setup.py install for mozscreenshot 09:06:34 INFO - Running setup.py install for moztest 09:06:34 INFO - Running setup.py install for mozversion 09:06:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:06:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:06:34 INFO - Cleaning up... 09:06:34 INFO - Return code: 0 09:06:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:06:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:34 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 0x10d536a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93a84869b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d49ad30>, '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 0x10d1ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d492b20>, '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.39CNihEE1A/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.tLsJcOGIHI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 09:06:34 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 09:06:34 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 09:06:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CNihEE1A/Render', 09:06:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:34 INFO - 'HOME': '/Users/cltbld', 09:06:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:34 INFO - 'LOGNAME': 'cltbld', 09:06:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:34 INFO - 'MOZ_NO_REMOTE': '1', 09:06:34 INFO - 'NO_EM_RESTART': '1', 09:06:34 INFO - 'PAGER': '/bin/cat', 09:06:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:34 INFO - 'PWD': '/builds/slave/test', 09:06:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:34 INFO - 'SHELL': '/bin/bash', 09:06:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tLsJcOGIHI/Listeners', 09:06:34 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 09:06:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:34 INFO - 'USER': 'cltbld', 09:06:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:34 INFO - 'XPC_FLAGS': '0x0', 09:06:34 INFO - 'XPC_SERVICE_NAME': '0', 09:06:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:06:34 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-rJ4Quw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:06:34 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)) 09:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:06:34 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-pRUilx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:06: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)) 09:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:06:35 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-nhr9fO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:06: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)) 09:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:06:35 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-n4Z0Kv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:06: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)) 09:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:06:35 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-EBzT2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:06: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)) 09:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:06:35 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-8nY_lk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:06: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)) 09:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:06:35 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-OrSuyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:06:35 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)) 09:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:06:35 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-wxXiV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:06:35 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)) 09:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:06:35 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-CqICuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:06:35 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)) 09:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:06:35 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-aajEao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:06:35 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)) 09:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:06:35 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-10ldXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:06:35 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)) 09:06:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:06:35 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-6zSE9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:06: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)) 09:06:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:06:36 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-hmm5hc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:06: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)) 09:06:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:06:36 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-OxmqNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:06: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)) 09:06:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:36 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-MDZ_T1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:06: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)) 09:06:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:06:36 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-f9p8Yc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:06:36 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)) 09:06:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:06:36 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-2ozbDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:06:36 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)) 09:06:36 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)) 09:06:36 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)) 09:06:36 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)) 09:06:36 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)) 09:06:36 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)) 09:06:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:06:36 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 09:06:36 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 09:06:36 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 09:06:36 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 09:06:36 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 09:06:36 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 09:06:38 INFO - Downloading blessings-1.6.tar.gz 09:06:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:06:39 INFO - Installing collected packages: blessings 09:06:39 INFO - Running setup.py install for blessings 09:06:39 INFO - Successfully installed blessings 09:06:39 INFO - Cleaning up... 09:06:39 INFO - Return code: 0 09:06:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:06:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:06:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:06:39 INFO - Reading from file tmpfile_stdout 09:06:39 INFO - Current package versions: 09:06:39 INFO - blessings == 1.6 09:06:39 INFO - blobuploader == 1.2.4 09:06:39 INFO - docopt == 0.6.1 09:06:39 INFO - functools32 == 3.2.3-2 09:06:39 INFO - jsonschema == 2.5.1 09:06:39 INFO - manifestparser == 1.1 09:06:39 INFO - mozInstall == 1.12 09:06:39 INFO - mozcrash == 0.17 09:06:39 INFO - mozdebug == 0.1 09:06:39 INFO - mozdevice == 0.48 09:06:39 INFO - mozfile == 1.2 09:06:39 INFO - mozhttpd == 0.7 09:06:39 INFO - mozinfo == 0.9 09:06:39 INFO - mozleak == 0.1 09:06:39 INFO - mozlog == 3.3 09:06:39 INFO - moznetwork == 0.27 09:06:39 INFO - mozprocess == 0.23 09:06:39 INFO - mozprofile == 0.28 09:06:39 INFO - mozrunner == 6.12 09:06:39 INFO - mozscreenshot == 0.1 09:06:39 INFO - mozsystemmonitor == 0.3 09:06:39 INFO - moztest == 0.7 09:06:39 INFO - mozversion == 1.4 09:06:39 INFO - psutil == 3.1.1 09:06:39 INFO - requests == 1.2.3 09:06:39 INFO - wsgiref == 0.1.2 09:06:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:39 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 0x10d536a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93a84869b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d49ad30>, '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 0x10d1ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d492b20>, '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.39CNihEE1A/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.tLsJcOGIHI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 09:06:39 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 09:06:39 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 09:06:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CNihEE1A/Render', 09:06:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:39 INFO - 'HOME': '/Users/cltbld', 09:06:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:39 INFO - 'LOGNAME': 'cltbld', 09:06:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:39 INFO - 'MOZ_NO_REMOTE': '1', 09:06:39 INFO - 'NO_EM_RESTART': '1', 09:06:39 INFO - 'PAGER': '/bin/cat', 09:06:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:39 INFO - 'PWD': '/builds/slave/test', 09:06:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:39 INFO - 'SHELL': '/bin/bash', 09:06:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tLsJcOGIHI/Listeners', 09:06:39 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 09:06:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:39 INFO - 'USER': 'cltbld', 09:06:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:39 INFO - 'XPC_FLAGS': '0x0', 09:06:39 INFO - 'XPC_SERVICE_NAME': '0', 09:06:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:39 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)) 09:06:39 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)) 09:06:39 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)) 09:06:39 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)) 09:06:39 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)) 09:06:39 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)) 09:06:39 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)) 09:06:39 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)) 09:06:39 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)) 09:06:39 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)) 09:06:39 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)) 09:06:39 INFO - Cleaning up... 09:06:39 INFO - Return code: 0 09:06:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:39 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 0x10d536a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5cb618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f93a84869b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d49ad30>, '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 0x10d1ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d492b20>, '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.39CNihEE1A/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.tLsJcOGIHI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 09:06:39 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 09:06:39 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 09:06:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CNihEE1A/Render', 09:06:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:39 INFO - 'HOME': '/Users/cltbld', 09:06:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:39 INFO - 'LOGNAME': 'cltbld', 09:06:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:39 INFO - 'MOZ_NO_REMOTE': '1', 09:06:39 INFO - 'NO_EM_RESTART': '1', 09:06:39 INFO - 'PAGER': '/bin/cat', 09:06:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:39 INFO - 'PWD': '/builds/slave/test', 09:06:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:39 INFO - 'SHELL': '/bin/bash', 09:06:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tLsJcOGIHI/Listeners', 09:06:39 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 09:06:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:39 INFO - 'USER': 'cltbld', 09:06:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:39 INFO - 'XPC_FLAGS': '0x0', 09:06:39 INFO - 'XPC_SERVICE_NAME': '0', 09:06:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:06:40 INFO - Cleaning up... 09:06:40 INFO - Return code: 0 09:06:40 INFO - Running post-action listener: _resource_record_post_action 09:06:40 INFO - Running post-action listener: _start_resource_monitoring 09:06:40 INFO - Starting resource monitoring. 09:06:40 INFO - [mozharness: 2016-08-26 16:06:40.200428Z] Finished create-virtualenv step (success) 09:06:40 INFO - [mozharness: 2016-08-26 16:06:40.201443Z] Running install step. 09:06:40 INFO - Running pre-action listener: _resource_record_pre_action 09:06:40 INFO - Running main action method: install 09:06:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:06:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:06:40 INFO - Reading from file tmpfile_stdout 09:06:40 INFO - Detecting whether we're running mozinstall >=1.0... 09:06:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:06:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:06:40 INFO - Reading from file tmpfile_stdout 09:06:40 INFO - Output received: 09:06:40 INFO - Usage: mozinstall [options] installer 09:06:40 INFO - Options: 09:06:40 INFO - -h, --help show this help message and exit 09:06:40 INFO - -d DEST, --destination=DEST 09:06:40 INFO - Directory to install application into. [default: 09:06:40 INFO - "/builds/slave/test"] 09:06:40 INFO - --app=APP Application being installed. [default: firefox] 09:06:40 INFO - mkdir: /builds/slave/test/build/application 09:06:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:06:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:07:14 INFO - Reading from file tmpfile_stdout 09:07:14 INFO - Output received: 09:07:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:07:14 INFO - Running post-action listener: _resource_record_post_action 09:07:14 INFO - [mozharness: 2016-08-26 16:07:14.545599Z] Finished install step (success) 09:07:14 INFO - [mozharness: 2016-08-26 16:07:14.545768Z] Running run-tests step. 09:07:14 INFO - Running pre-action listener: _resource_record_pre_action 09:07:14 INFO - Running main action method: run_tests 09:07:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:07:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:07:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:07:14 INFO - 2016-08-26 09:07:14.560 screenresolution[1658:8115] starting screenresolution argv=screenresolution get 09:07:14 INFO - 2016-08-26 09:07:14.563 screenresolution[1658:8115] Display 0: 1600x1200x32@60 09:07:14 INFO - 2016-08-26 09:07:14.568 screenresolution[1659:8122] starting screenresolution argv=screenresolution list 09:07:14 INFO - Available Modes on Display 0 09:07:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:07:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:07:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:07:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:07:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:07:14 INFO - Intel Iris: 09:07:14 INFO - Chipset Model: Intel Iris 09:07:14 INFO - Type: GPU 09:07:14 INFO - Bus: Built-In 09:07:14 INFO - VRAM (Dynamic, Max): 1536 MB 09:07:14 INFO - Vendor: Intel (0x8086) 09:07:14 INFO - Device ID: 0x0a2e 09:07:14 INFO - Revision ID: 0x0009 09:07:14 INFO - Displays: 09:07:14 INFO - Display: 09:07:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:07:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:07:14 INFO - Display Serial Number: 1600x1200 60 09:07:14 INFO - Main Display: Yes 09:07:14 INFO - Mirror: Off 09:07:14 INFO - Online: Yes 09:07:14 INFO - Rotation: Supported 09:07:14 INFO - Return code: 0 09:07:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:07:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:07:14 INFO - Python 2.7.3 09:07:14 INFO - Return code: 0 09:07:14 INFO - grabbing minidump binary from tooltool 09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:14 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 0x10d49ad30>, '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 0x10d1ffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d492b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:07:14 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 09:07:14 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 09:07:14 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 09:07:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:07:14 INFO - Return code: 0 09:07:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:07:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:07:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:07:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:07:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:07:14 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/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0370', '--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 09:07:14 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/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0370 --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 09:07:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.39CNihEE1A/Render', 09:07:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:14 INFO - 'HOME': '/Users/cltbld', 09:07:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:14 INFO - 'LOGNAME': 'cltbld', 09:07:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:07:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:07:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:14 INFO - 'MOZ_NO_REMOTE': '1', 09:07:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:07:14 INFO - 'NO_EM_RESTART': '1', 09:07:14 INFO - 'PAGER': '/bin/cat', 09:07:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:14 INFO - 'PWD': '/builds/slave/test', 09:07:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:07:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:14 INFO - 'SHELL': '/bin/bash', 09:07:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tLsJcOGIHI/Listeners', 09:07:14 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 09:07:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:14 INFO - 'USER': 'cltbld', 09:07:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:14 INFO - 'XPC_FLAGS': '0x0', 09:07:14 INFO - 'XPC_SERVICE_NAME': '0', 09:07:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:14 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/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0370', '--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 09:07:15 INFO - mozversion application_buildid: 20160826063820 09:07:15 INFO - mozversion application_changeset: a65b35c8e5b17c2585968974aef1da67a8c56642 09:07:15 INFO - mozversion application_display_name: Nightly 09:07:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:07:15 INFO - mozversion application_name: Firefox 09:07:15 INFO - mozversion application_remotingname: firefox 09:07:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:07:15 INFO - mozversion application_vendor: Mozilla 09:07:15 INFO - mozversion application_version: 51.0a1 09:07:15 INFO - mozversion platform_buildid: 20160826063820 09:07:15 INFO - mozversion platform_changeset: a65b35c8e5b17c2585968974aef1da67a8c56642 09:07:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:07:15 INFO - mozversion platform_version: 51.0a1 09:07:15 INFO - using testdate: 1472227635 09:07:15 INFO - actual date: 1472227635 09:07:15 INFO - starting webserver on 'localhost:49269' 09:07:15 INFO - SUITE-START | Running 2 tests 09:07:15 INFO - TEST-START | dromaeo_css 09:07:15 INFO - operating with platform_type : mac_ 09:07:15 INFO - Initialising browser for dromaeo_css test... 09:07:15 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/tmp5eBevu/profile http://localhost:49269/getInfo.html) 09:07:17 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 09:07:17 INFO - PROCESS | 1669 | colorDepth:24 09:07:17 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 09:07:17 INFO - PROCESS | 1669 | __metrics 09:07:17 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:07:17 INFO - PROCESS | 1669 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:07:18 INFO - TEST-INFO | 1669: exit 0 09:07:18 INFO - Browser initialized. 09:07:18 INFO - Running cycle 1/1 for dromaeo_css test... 09:07:18 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/tmp5eBevu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:07:30 INFO - PROCESS | 1673 | RSS: Main: 233586688 09:07:30 INFO - PROCESS | 1673 | 09:08:22 INFO - PROCESS | 1673 | 1472227702451 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] 09:10:33 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49269/tests/dromaeo/cssquery-ext.html) 09:10:35 INFO - PROCESS | 1673 | RSS: Main: 410517504 09:10:35 INFO - PROCESS | 1673 | 09:13:38 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-ext.html (next: http://localhost:49269/tests/dromaeo/cssquery-jquery.html) 09:13:39 INFO - PROCESS | 1673 | RSS: Main: 377323520 09:13:39 INFO - PROCESS | 1673 | 09:16:41 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49269/tests/dromaeo/cssquery-mootools.html) 09:16:42 INFO - PROCESS | 1673 | RSS: Main: 360316928 09:16:42 INFO - PROCESS | 1673 | 09:19:44 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49269/tests/dromaeo/cssquery-prototype.html) 09:19:45 INFO - PROCESS | 1673 | RSS: Main: 339931136 09:19:45 INFO - PROCESS | 1673 | 09:22:48 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49269/tests/dromaeo/cssquery-yui.html) 09:22:49 INFO - PROCESS | 1673 | RSS: Main: 325844992 09:22:49 INFO - PROCESS | 1673 | 09:25:52 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-yui.html (next: http://localhost:49269/tests/dromaeo/cssquery-dojo.html) 09:25:53 INFO - PROCESS | 1673 | RSS: Main: 374587392 09:25:53 INFO - PROCESS | 1673 | 09:25:53 INFO - PROCESS | 1673 | __start_tp_report 09:25:53 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 09:25:53 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 09:25:53 INFO - PROCESS | 1673 | |i|pagename|runs| 09:25:53 INFO - PROCESS | 1673 | |0;dojo.html;3329.9001029969104;3338.666133386661;3345.648706885777;3374.595048594169;3415.897523074307;5525.171224316355;5630.042892708239;5665.206871038058;5689.914651280229;5707.2009918611375;5646.237757902682;5667.716614169296;5748.5401167906575;5765.1928729977835;5795.072788353864;5045.722485263309;5175.792968281283;5184.559312458427;5194.766235519392;5305.4164041955355;5297.920531192035;5299.60252981026;5304.973475132619;5312.096943519592;5336.813211537577;5127.282180494733;5167.48325167484;5170.508801663836;5181.585473162157;5228.633995620308;5196.960607878402;5214.478552144793;5216.217567364888;5231.947680523184;5231.95360927816;5094.337736094284;5138;5166.9224961625605;5187.144121219994;5190.870228244286;5557.3886872444;5559.305086864142;5601.887962240732;5715.971420142914;5761.539076873882;6100.23747031621;6143.1092491588915;6167.506599472032;6187.721552530147;6202.069689546604;5139.8458046258675;6119.87760244797;6145.064304744036;6244.594101383395;6285.779997700059;5724.39893811148;5753.942460575425;5754.964106460793;5776.56675749312;5785.82642520725;6257.405546473078;6267;6280.560360774702;6302.149209856702;6339.302676705559;5848.970755146197;5939.613925094855;5940.316863560746;6082.2701275847185;6113.358097399798;6089.269287685417;6141.692915354214;6146.846328841814;6149.723262453201;6165.753369865245;6037.45662890342;6134.681512609229;6141.087130295746;6183.134361189437;6190.442860142519;6107.6640784757265;6182.969085154546;6187.288461826947;6189.659568723763;6204.751809927552;4908.631852610997;4910.140725373046;4920.581750551171;4927.310176575283;4962.578180854668;4344.087741574304;4353.1293741251875;4392.9121417572105;4416.6025057744955;4456.041950980555;5638.661680299195;5644.040513112781;5653.943460565424;5656.349519805275;5681.2330335404195;6171.197744293213;6175.783847565313;6233.937660623426;6271.122042913996;6271.404216599416;4437.534058923831;5540.750666220029;5591.10542313228;5595.63628364155;5671.064274394678;5402.702851343214;5413.052715774724;5430.266913966643;5434.619576629598;5467.644603100785;6267.561270711007;6303.212098487689;6305.991041433349;6327.810165695037;6332.240131184288;6052.425019623129;6114.816555503342;6124.258880972441;6249.718762655601;6257.217847769029;6226.750929962751;6239.126522286974;6271.153394291712;6285.622862628257;6307.3377295383325;5940.376260492586;5966.642001479751;5973.880522389441;6006;6038.849028774316;6075.513958883367;6102.664353460602;6109.419605137505;6113.388661134029;6144.938550614436;5843.2695913010875;6015.759369625166;6016.759329626941;6025.246844144482;6050.334463209131;5897.085951677497;5914.852128696645;5944.881102377842;5974.671393073423;6054.697265136813;5801.82594522165;5825.970870145622;5838.5621078418435;5841.9707901461925;5842.941570584239;5885.61743486672;6008.339082700987;6013.458788709037;6022.5784195105925;6027.427394397525;5741.540676745767;5747.9137812932;5762.049261871744;5790.623609465371;5825.563082768894 09:25:53 INFO - PROCESS | 1673 | |1;ext.html;483.46818499650385;488.2554104989892;526.7287347016285;531.1899353485934;536.32423146835;31290.843545782296;31971.840140799293;33383.49924751131;33393.16517087074;33408.66591334089;34037.48943765839;39967;41277.55528556494;44356;46529.83675408117;9704.078112579293;9848;9851.261155413336;9852.211823054138;9882.90117098827;9583.856242156373;9670.468124253162;9690.273229507815;9713.95143024284;9716.90283097167;8787;8925.687600933968;12095.516179352815;12372.319522426264;12402.627921162379;12381.93809030958;12389.318587477685;12389.566365177176;12400.565980190695;12440.937795311056;8749.03760586335;12049.03607711381;12142.332171730552;12144.817827732591;12157.939210303892;41157.38263926043;41920.79039604813;42047.789761051;44161.33757993633;44309.904360998255;2727.972720272792;3331.90047284398;3377.4258376076127;3596.4425514045365;3758.9812050939845;31311.84344078288;31466;31511.212219694462;31584.684153158403;31948.840255798805;3141.575887255214;3234.32079263355;3269.9019029429155;3328.5007248912616;3345.7992520448606;454245;458746;460245;460543;462569.9999999966;62619.060714089326;62873;63006;63039.6848015757;63062;55570;64236.71526569536;65318.67340663266;65429.345706543274;69371;16294.022358658518;16794;16818.159092045593;16835.831641683428;16865.156742162842;12944;13044.282564458856;13051;13059.281739504419;13070.673233169056;15916.204189790464;16267.674646507003;18149.001804900858;18217.817821821878;18333.541661458305;5949.940500595025;6412.877746394455;6525.151730275129;6742.157230346207;6798.932010679929;847.9788005299916;1019.0522813783252;1019.37308555238;1024.933379330341;1068.2522234435771;28581.285467863054;29869.70130298669;30056.54915176274;30558.84720576383;30845;864.5115509737104;1042.4475028235047;1049.8110340138849;1070.823314153156;1121.5401685308984;13161.60515184546;15580.376784928703;15809.36762529509;15952.964038905624;16043.679126417406;562175;566892;567006;567459;568446;46675.29987050197;50121.24818127731;59106;59307.703461482415;59514.404855951754;1319.9274039927705;1347.2590075458459;1362.3528823808613;1363.4137320951888;1367.8974076944269;30434.543481847348;31537.8423107883;31927.967571532223;32085.03744887657;32119;50714.74642626763;51086;51152.23271650854;51285.230721539956;51289;644245;661646;662350;840628;840764;26026.089086881868;26143.607845882707;26243.60634590521;26274;26284.21147365582;38732.61267387403;42486.15027699367;43900.780496097315;46216.30675539937;48071.75964120157;35102;37496;38586.614133858304;39438;39902.60097399105;36193.81903090468;38283.61716382801;38421.231575368896;39110.804445977585;39639.80180099196;37892.810535947145;38845.6115438842;38958.4156237651;39432.80283598564;41135;31465.68534314719;33806.661933380354;35239.64760352466;35427.64572354243;35439.82280088583;25061.24816255515;25509.489810203322;25630.871845640653;25649.743502564736;26086 09:25:53 INFO - PROCESS | 1673 | |2;jquery.html;347.3591224191366;365.65628309389183;376.3658235872553;378.6459660217696;401.8854626431466;15423.305951232194;16333.428330008452;19904;20695.861727604977;20827.791722082773;19955.201791928306;20483.385498435033;20506.974651267414;20518.38444846653;21329.893350533224;5223.503767142115;5811.6803575803315;5972.850678733023;6073.362296958822;6080.1183828344865;4458.799354029076;6031.577789554733;6038.69806509675;6039.879202415949;6046.304674962392;5693.943060569382;5900.350961394243;5976.581639285252;5978.9701051494685;5981.611195272315;5924.348321684612;5963.224780778514;5965;5967.075103358986;5970.791022314198;5036.597072234213;5036.647434679574;5188.11801993662;5190.2993095932325;5190.32525771651;26406.6039009415;26505.46989060208;26583.601245981325;26724.866375667996;26823;2269.4212975691257;2295.3228797504776;2344.10923848938;2397.6523404106306;2421.6609674645565;15467.690646187013;15956.4415245467;16152.515424537283;16294.83705162945;16645.358352776148;1873.4379686094117;1878.690016147334;1906.447130332202;1926.7784204816346;1995.4911497568173;89022.5548872258;115393.42303288514;121769;124076;124465.37767311106;10764.801727326349;10978.066864316463;10982;11074.058705010164;11301.26541774782;13709.245991470365;13744.793828092586;13745.793813092812;13748.862511374959;13750.724985500234;8431.494110353398;8715.346349023848;8744.344174186834;8876.20114189726;8880.245179159843;7237.819054523679;8798.86801697975;8805.207531322212;8816.162464565856;9019.729408117766;5728.885422291614;6211.844703882349;6634.568753031038;6646.4350530205475;6788.796336109925;1813.9365122220659;1837.9107527201822;1858.8791728537872;1862.3761040051734;1898.9525261868287;846.9830603388021;929.697848199338;955.9761005974767;1044.1646682653848;1048.3919326790597;21128.471788205417;21491.355259342246;22074.44813879666;22647.773522264895;22721.659175112385;911.7766147293955;929.2658799548416;941.1999800169801;955;1003.5132960514145;9895.455749933823;10438.008389203014;10756.56973721058;11729.941350293364;11869.762604747857;562876;563602;563757;580282;582483;75589;109702;114508;114875;119581.40209299071;950.5532399772096;978.7357413498315;993.7515621094726;994.4294000009911;996.8386829343733;18448.63102737911;19714.802851971297;20150;20163;20239;52931.6767080826;53058.73470632622;53487.73256133695;53925.73037134789;54495.455045450624;64295.67852160709;72931.17672058241;76128.85806712793;77893.61053194698;81253;7491.662875170739;7859.842803143791;7990.400719945911;8156.796080098045;8312.41813073079;7265.164506081868;10066.993300669874;11376.886231137914;12654;13475.797863031867;10988.615398461016;11058.557657693602;11298.209125361474;11338.036266917405;11343;10854.29447085937;10944.39805810688;10951.780964380827;11288.661340159808;11453.48459319316;9551.856722149034;11304.717382065513;11367.431628418712;11619.593314233962;11696.766064678828;6476.449501792212;7156.570605763671;7358.11702595692;7471.402287816853;7627.694892204471;9792.902070979198;10078.748031298983;10254.23093267993;10992.835107473235;11244.325340479598 09:25:53 INFO - PROCESS | 1673 | |3;mootools.html;1513.3341329814884;1518.3091693279555;1710.6236627941848;1852.1665250637216;1877.6057028024113;8434.746957591275;9028.32287578431;9034.096590340963;9035.096490350961;9041.095890410956;7848.332891704212;8101.5544145072;8367.079621241659;8720.34597405194;8851.070637583041;2112.640851055324;2286.748457669655;2346.120204923158;2396.940076498093;2423.5879900416885;2344.601417758985;2348.894299756515;2399.676043734091;2402.1112188490197;2404;2368.301351101429;2383.30884043628;2387.1286980252257;2402.1832576923844;2410.855348679076;2330.8135349172026;2354.1407386303963;2355.552445035438;2358.5518751437257;2377;2323.5236776460783;2352.1061996441413;2352.7059117610297;2360.3627020704484;2367.2188177901426;8694.826103477959;8711.912880871174;8794.296456283546;8797.912020879838;8798.340124490689;5263.236830659533;5418.051840927822;5422.728863556846;5427.728613569345;5440.700761458118;3618.475321078429;3651.7261205409436;3687.3731465650903;3720.627937206285;3742.6257374262627;1086.9293495922739;1096.5778175402447;1099.5326986030905;1106.7675788084512;1118.703543560957;11360.204785664924;11514.251573647685;11596.536138554447;11605.129615278887;11606;4554.20301447252;4590.449146102489;4810.302506136591;4816.20532612115;4851.684640498356;9947.900520994843;9961.203103751683;9961.601535938626;9962.701118966443;9979;5329.733513324319;5333.146696528537;5339.973300133553;5345.1714984177515;5356.143017117242;3892.9416058759143;4337.652987760972;4493.078918821634;4821.831235906727;4871.123197824426;3229.4671379222164;3915.099527108781;4005.9799701001307;4026.7181297308907;4053.3717273822604;1265.7278685082752;1293.5020017293223;1294.3140135728079;1302.6743314171458;1312.7637025335532;837.2046555772041;845.7166849105496;886.1847100667402;893.2585953658448;931.334096121263;5458.45415458459;5694.914576281359;5726.9713651431475;5858.47273745365;5875.265591801025;903.1555495611624;903.4443817052597;903.9683611073713;913.6162811619135;920.1672486399818;3710.0910276982313;3763.379042457964;3765.755225910307;3813.866514671973;3864.9227015459533;10392.168626509861;10646.787064258826;10777.137828973664;10777.299475534066;10777.353358798497;10660;10675.893241067646;10687.14502839771;10698.465076746288;10708.250422470508;6915.688794004282;8059.274665280153;8115.837683246302;8184.918150818654;8213.671453141927;5324.281222035131;5563.833085007456;5599.468050535193;5630.7466164023535;5686;7992;9790.951045244728;9962.651307204213;9985.700428987142;10045.899541004497;9773.169280611228;10064;10252.333598316085;10364.740881478023;10391.896081039395;6485.708143133476;6693.866122677617;6833.760818371333;6970.059042029261;7125.714971401293;8133.0646975598565;8471.830563388821;8480.83038339242;8486;8487.236148746642;6140.3552626975315;6245.531585131042;7513.774586762406;7586.696532138652;7604.5437273763755;7295;7340.11918569775;7377.668004939684;7393.112826460859;7423.146338170964;6752.324767523209;6795.864082718417;6889.448844092361;6902.378785909293;6908.2400935896;4756.524347565216;4792.784324705327;4825.445073816556;4842.975785121052;4935.605151587936;4228.5982831630945;4737.0525894820485;4816.494268101799;4894.094592500399;5003.124453220599 09:25:53 INFO - PROCESS | 1673 | |4;prototype.html;297.8763813017598;300.11915029388746;309.26703712202084;311.6711868978227;311.8877204206485;4560.6123479504195;4898.779554920024;5539.667619942807;5543.362513310965;5583.581231407646;5149.382074151098;5174.275601415805;5298.046351656701;5430.995265875806;5517.200005999128;3392.8473218705158;3882.903232254579;4054.209429161315;4118.341065429532;4153.380181729128;3581.3553560359123;3795.7532760370627;4167.708260421772;4176.97911510442;4194.78351278129;3447.913802154941;4016.919661606766;4068.9186216275657;4086.3257562502154;4134.627883490485;4021.738586991836;4096.8566100186645;4098.241825262336;4114.362273847559;4116.197341518405;3536.169000284931;3543.5216245806746;3554.8578056877695;3565.554305711786;3571.5178450909057;3748.475213470116;4686.812527498896;4996.825745949699;5189.379363421485;5248.921266181011;4180.453232304037;4661.160991021615;4676.456769266134;4687.086018226447;4735;5642.125470552071;5674.631148975303;5691.577105723569;5723.656580605177;5727.140928860663;3448.9655103449145;3851.656645017295;3894.5131858517684;3927.175293188434;3939.72421930465;4824.565789078964;5926.318473375531;6041.788537401214;6052.727627256784;6057.606255593417;3633.0554055945645;3941.187053955213;3957.2679054375035;3979.8209080590864;3984.4222587724616;3979.010494752624;3996.100877302642;4000.119973605802;4001.139754952641;4003.939940900889;3555.839987200582;4097.344424892062;4172.853950111731;4434.401355816988;4618.676692631483;4821.252705830602;5423.9694458052845;5701.914471282934;5707.200991861143;5724.313082430135;1264.9430775614935;1277.923324600527;1280.0271793436868;1347.1445632023735;1363.754524185656;742.8774252248427;851.2126283188027;904.5206040798388;907.6732376344511;914.533587870191;4674.368960190399;4881.999190166079;5103.234514822732;5314.5216930476445;5323.813666521653;4468.285074388083;4504.594586487232;5095.898082038412;5892.616979896293;5922.371347879368;4536.6143877770755;5125.769340379692;5375.951689420525;5410.83767486976;5546.667199968015;2733.248356701923;3122.625284965773;3123.4846250368887;3137.231378312328;3147.68523147688;4985.127602669519;5476.397596264408;5854.511832471183;6248.75004999804;6308.653024083627;4998.9500104999215;5004.648744838873;6026.427489388501;6119.602225855305;6123.8162855114415;5035.446100928895;6049.606775559662;6137.815865524041;6157.491414603451;6223.603912493632;5156.278121062979;5410.918836217538;5609.747561359667;5934.635033942303;6057.121717350871;5235.01069593552;5465.180222966586;5906.320773110975;5922.378150294157;5953.910691339547;5394.974954758583;5541.469257257645;5823.272090988627;6210.720517576629;6297.496200304056;3087.3516561522333;3849.653531182207;3889.444222311098;3910.941335879907;3950.30869597825;4847.61219102478;5072.315237442898;5317.627766056494;5986.461218490357;5988.610740301867;4752.310914917387;5503.009458297545;5746.770129194785;5794.768209271582;5816.952948469316;4647.4655414626395;5126;5289.8677533060445;5692.402297758841;5764.962306784819;5189.429162792015;5365.248865158803;5381.246625472359;5560.942451293047;5699.230603868425;5265.368155821173;5393.83818485446;5708.628939118944;5727.599068065362;5814.505767009682;4882.975585122052;5025.623078269217;5594.748236329294;5597.63615365;5655.7840064386355 09:25:53 INFO - PROCESS | 1673 | |5;yui.html;606.3724045612792;678.0405725897855;684.185818875538;743.5315751076821;831.9625616847242;17210.827891721077;17901.552461188476;17930.372436964677;17936.19287132079;17940.372086976975;16117.032978021298;16253.431129910456;16333;16352.83647163528;16368.508944731677;662.2185820731535;854.8162145138779;881.5371929736875;885.579349808842;887.2901678657081;686.2279935073043;843.6119385082869;856.327782690588;868.3140319147866;875.3566128895256;919.0855099176296;920.1442658327752;920.5949382271813;922.1608336413865;924.0713083351222;710.523948954202;917.7751450894507;918.1598837064079;918.274563095154;919.2737737187614;543.6330476928098;546.8742189296445;546.9206964990094;661.1174082599736;680.8978653202009;15469.381224750996;15504.922475387664;15509.689806203813;15531.223438828127;15537.533873983686;14040.648983775385;14294.499692510712;14468.710625787426;14513.637159071002;14579.979401441904;12977.35113244334;13056.543020994219;13076.280804555745;13331.733365332737;13661.316934153252;3775.4714339992424;4471.418715566955;4572.428446444194;4662.370579971728;4731.597814185764;121582.39208803899;123473.9999999991;123498.38250808688;126423;126634;8932.732018039469;10926.890731092746;11186.888131118585;11507.424628768527;11667.474963626488;22780.43048923757;23090.307290781304;23122.768772312396;23138.3058508245;23153.53692926132;6428.8071357859335;7752.18602046777;7789.649465774054;7972.1265502608;7991.64037618297;4299;4415.116976604692;4431.534688857688;4457.331400289982;4460.910781784346;2979.4190132923873;3802.1825307558456;3904.9609503905167;4104.897377565585;4116.711830171919;720.5064530796421;722.5809030762146;724.6521669598517;732.9926700733031;740.6000759589868;442.9667774916894;576.847135509082;640.8846407646575;641.214512222533;673.4242222899351;8560.743034055728;8608.34145488165;9000.848007183198;9185.908140918638;9290.581923813445;519.3274709251529;539.6788910598187;579.9159121927381;586.2876604925036;607.6756406744221;4291.935620965688;4547.658925580595;4549.476810166807;4551.135284295973;4554.590086892195;163992.18003909904;164164;164272.17863910605;165642;169521;99840.500797497;141617;141709.8743518848;148055.25972370285;150639;115359;121507;122792.38603807102;123292;126197.73802261859;8632.870506942276;8735.737927862174;8771.736847894574;8897.332700047651;9015.233705135137;56978.715106424206;57885.71057144687;59996.50008749642;61833.072503913376;66654.33345666481;84885;85571.57214213889;86189.1381086181;91910.08089919016;93838;11609.47757350938;12448.439820207932;12754.48982040745;13139.560417623023;13342.399592018577;14664.3401046951;15025.624359390666;15135.318910648826;15356.846431535541;15650.530484085495;13675.589732308046;13896.37466313998;14115.788263175855;14246.501372452329;14401.855981440052;13590.796138058135;13665.726685466434;13961.860381396056;14084.647883802987;14549.636259093608;13142.737145257233;13537.187768733513;13624.114432561852;13640.522581709592;13723.931380343034;11429.542818287175;11489.342722772059;11504.252223605774;11664.183507154417;11771;11224.887751122384;11376.805842196885;11435.371054592077;11459.541618335174;11492.712682182679 09:25:53 INFO - PROCESS | 1673 | __end_tp_report 09:25:53 INFO - PROCESS | 1673 | __start_cc_report 09:25:53 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1595 09:25:53 INFO - PROCESS | 1673 | __end_cc_report 09:25:53 INFO - PROCESS | 1673 | __startTimestamp1472228753159__endTimestamp 09:25:53 INFO - PROCESS | 1673 | 09:25:53 INFO - PROCESS | 1673 | ------- Summary: start ------- 09:25:53 INFO - PROCESS | 1673 | Number of tests: 6 09:25:53 INFO - PROCESS | 1673 | 09:25:53 INFO - PROCESS | 1673 | [#0] dojo.html Cycles:165 Average:5678.93 Median:5825.97 stddev:621.54 (10.7%) stddev-sans-first:595.50 09:25:53 INFO - PROCESS | 1673 | Values: 3329.9 3338.7 3345.6 3374.6 3415.9 5525.2 5630.0 5665.2 5689.9 5707.2 5646.2 5667.7 5748.5 5765.2 5795.1 5045.7 5175.8 5184.6 5194.8 5305.4 5297.9 5299.6 5305.0 5312.1 5336.8 5127.3 5167.5 5170.5 5181.6 5228.6 5197.0 5214.5 5216.2 5231.9 5232.0 5094.3 5138.0 5166.9 5187.1 5190.9 5557.4 5559.3 5601.9 5716.0 5761.5 6100.2 6143.1 6167.5 6187.7 6202.1 5139.8 6119.9 6145.1 6244.6 6285.8 5724.4 5753.9 5755.0 5776.6 5785.8 6257.4 6267.0 6280.6 6302.1 6339.3 5849.0 5939.6 5940.3 6082.3 6113.4 6089.3 6141.7 6146.8 6149.7 6165.8 6037.5 6134.7 6141.1 6183.1 6190.4 6107.7 6183.0 6187.3 6189.7 6204.8 4908.6 4910.1 4920.6 4927.3 4962.6 4344.1 4353.1 4392.9 4416.6 4456.0 5638.7 5644.0 5653.9 5656.3 5681.2 6171.2 6175.8 6233.9 6271.1 6271.4 4437.5 5540.8 5591.1 5595.6 5671.1 5402.7 5413.1 5430.3 5434.6 5467.6 6267.6 6303.2 6306.0 6327.8 6332.2 6052.4 6114.8 6124.3 6249.7 6257.2 6226.8 6239.1 6271.2 6285.6 6307.3 5940.4 5966.6 5973.9 6006.0 6038.8 6075.5 6102.7 6109.4 6113.4 6144.9 5843.3 6015.8 6016.8 6025.2 6050.3 5897.1 5914.9 5944.9 5974.7 6054.7 5801.8 5826.0 5838.6 5842.0 5842.9 5885.6 6008.3 6013.5 6022.6 6027.4 5741.5 5747.9 5762.0 5790.6 5825.6 09:25:53 INFO - PROCESS | 1673 | 09:25:53 INFO - PROCESS | 1673 | [#1] ext.html Cycles:180 Average:71602.62 Median:19895.13 stddev:160308.03 (805.8%) stddev-sans-first:160668.81 09:25:53 INFO - PROCESS | 1673 | Values: 483.5 488.3 526.7 531.2 536.3 31290.8 31971.8 33383.5 33393.2 33408.7 34037.5 39967.0 41277.6 44356.0 46529.8 9704.1 9848.0 9851.3 9852.2 9882.9 9583.9 9670.5 9690.3 9714.0 9716.9 8787.0 8925.7 12095.5 12372.3 12402.6 12381.9 12389.3 12389.6 12400.6 12440.9 8749.0 12049.0 12142.3 12144.8 12157.9 41157.4 41920.8 42047.8 44161.3 44309.9 2728.0 3331.9 3377.4 3596.4 3759.0 31311.8 31466.0 31511.2 31584.7 31948.8 3141.6 3234.3 3269.9 3328.5 3345.8 454245.0 458746.0 460245.0 460543.0 462570.0 62619.1 62873.0 63006.0 63039.7 63062.0 55570.0 64236.7 65318.7 65429.3 69371.0 16294.0 16794.0 16818.2 16835.8 16865.2 12944.0 13044.3 13051.0 13059.3 13070.7 15916.2 16267.7 18149.0 18217.8 18333.5 5949.9 6412.9 6525.2 6742.2 6798.9 848.0 1019.1 1019.4 1024.9 1068.3 28581.3 29869.7 30056.5 30558.8 30845.0 864.5 1042.4 1049.8 1070.8 1121.5 13161.6 15580.4 15809.4 15953.0 16043.7 562175.0 566892.0 567006.0 567459.0 568446.0 46675.3 50121.2 59106.0 59307.7 59514.4 1319.9 1347.3 1362.4 1363.4 1367.9 30434.5 31537.8 31928.0 32085.0 32119.0 50714.7 51086.0 51152.2 51285.2 51289.0 644245.0 661646.0 662350.0 840628.0 840764.0 26026.1 26143.6 26243.6 26274.0 26284.2 38732.6 42486.2 43900.8 46216.3 48071.8 35102.0 37496.0 38586.6 39438.0 39902.6 36193.8 38283.6 38421.2 39110.8 39639.8 37892.8 38845.6 38958.4 39432.8 41135.0 31465.7 33806.7 35239.6 35427.6 35439.8 25061.2 25509.5 25630.9 25649.7 26086.0 09:25:53 INFO - PROCESS | 1673 | 09:25:53 INFO - PROCESS | 1673 | [#2] jquery.html Cycles:180 Average:33652.01 Median:26773.93 stddev:94920.33 (354.5%) stddev-sans-first:95153.67 09:25:53 INFO - PROCESS | 1673 | Values: 347.4 365.7 376.4 378.6 401.9 15423.3 16333.4 19904.0 20695.9 20827.8 19955.2 20483.4 20507.0 20518.4 21329.9 5223.5 5811.7 5972.9 6073.4 6080.1 4458.8 6031.6 6038.7 6039.9 6046.3 5693.9 5900.4 5976.6 5979.0 5981.6 5924.3 5963.2 5965.0 5967.1 5970.8 5036.6 5036.6 5188.1 5190.3 5190.3 26406.6 26505.5 26583.6 26724.9 26823.0 2269.4 2295.3 2344.1 2397.7 2421.7 15467.7 15956.4 16152.5 16294.8 16645.4 1873.4 1878.7 1906.4 1926.8 1995.5 89022.6 115393.4 121769.0 124076.0 124465.4 10764.8 10978.1 10982.0 11074.1 11301.3 13709.2 13744.8 13745.8 13748.9 13750.7 8431.5 8715.3 8744.3 8876.2 8880.2 7237.8 8798.9 8805.2 8816.2 9019.7 5728.9 6211.8 6634.6 6646.4 6788.8 1813.9 1837.9 1858.9 1862.4 1899.0 847.0 929.7 956.0 1044.2 1048.4 21128.5 21491.4 22074.4 22647.8 22721.7 911.8 929.3 941.2 955.0 1003.5 9895.5 10438.0 10756.6 11729.9 11869.8 562876.0 563602.0 563757.0 580282.0 582483.0 75589.0 109702.0 114508.0 114875.0 119581.4 950.6 978.7 993.8 994.4 996.8 18448.6 19714.8 20150.0 20163.0 20239.0 52931.7 53058.7 53487.7 53925.7 54495.5 64295.7 72931.2 76128.9 77893.6 81253.0 7491.7 7859.8 7990.4 8156.8 8312.4 7265.2 10067.0 11376.9 12654.0 13475.8 10988.6 11058.6 11298.2 11338.0 11343.0 10854.3 10944.4 10951.8 11288.7 11453.5 9551.9 11304.7 11367.4 11619.6 11696.8 6476.4 7156.6 7358.1 7471.4 7627.7 9792.9 10078.7 10254.2 10992.8 11244.3 09:25:53 INFO - PROCESS | 1673 | 09:25:53 INFO - PROCESS | 1673 | [#3] mootools.html Cycles:180 Average:5632.65 Median:4861.40 stddev:3166.32 (65.1%) stddev-sans-first:3160.07 09:25:53 INFO - PROCESS | 1673 | Values: 1513.3 1518.3 1710.6 1852.2 1877.6 8434.7 9028.3 9034.1 9035.1 9041.1 7848.3 8101.6 8367.1 8720.3 8851.1 2112.6 2286.7 2346.1 2396.9 2423.6 2344.6 2348.9 2399.7 2402.1 2404.0 2368.3 2383.3 2387.1 2402.2 2410.9 2330.8 2354.1 2355.6 2358.6 2377.0 2323.5 2352.1 2352.7 2360.4 2367.2 8694.8 8711.9 8794.3 8797.9 8798.3 5263.2 5418.1 5422.7 5427.7 5440.7 3618.5 3651.7 3687.4 3720.6 3742.6 1086.9 1096.6 1099.5 1106.8 1118.7 11360.2 11514.3 11596.5 11605.1 11606.0 4554.2 4590.4 4810.3 4816.2 4851.7 9947.9 9961.2 9961.6 9962.7 9979.0 5329.7 5333.1 5340.0 5345.2 5356.1 3892.9 4337.7 4493.1 4821.8 4871.1 3229.5 3915.1 4006.0 4026.7 4053.4 1265.7 1293.5 1294.3 1302.7 1312.8 837.2 845.7 886.2 893.3 931.3 5458.5 5694.9 5727.0 5858.5 5875.3 903.2 903.4 904.0 913.6 920.2 3710.1 3763.4 3765.8 3813.9 3864.9 10392.2 10646.8 10777.1 10777.3 10777.4 10660.0 10675.9 10687.1 10698.5 10708.3 6915.7 8059.3 8115.8 8184.9 8213.7 5324.3 5563.8 5599.5 5630.7 5686.0 7992.0 9791.0 9962.7 9985.7 10045.9 9773.2 10064.0 10252.3 10364.7 10391.9 6485.7 6693.9 6833.8 6970.1 7125.7 8133.1 8471.8 8480.8 8486.0 8487.2 6140.4 6245.5 7513.8 7586.7 7604.5 7295.0 7340.1 7377.7 7393.1 7423.1 6752.3 6795.9 6889.4 6902.4 6908.2 4756.5 4792.8 4825.4 4843.0 4935.6 4228.6 4737.1 4816.5 4894.1 5003.1 09:25:53 INFO - PROCESS | 1673 | 09:25:53 INFO - PROCESS | 1673 | [#4] prototype.html Cycles:180 Average:4558.62 Median:5053.88 stddev:1417.59 (28.0%) stddev-sans-first:1385.03 09:25:53 INFO - PROCESS | 1673 | Values: 297.9 300.1 309.3 311.7 311.9 4560.6 4898.8 5539.7 5543.4 5583.6 5149.4 5174.3 5298.0 5431.0 5517.2 3392.8 3882.9 4054.2 4118.3 4153.4 3581.4 3795.8 4167.7 4177.0 4194.8 3447.9 4016.9 4068.9 4086.3 4134.6 4021.7 4096.9 4098.2 4114.4 4116.2 3536.2 3543.5 3554.9 3565.6 3571.5 3748.5 4686.8 4996.8 5189.4 5248.9 4180.5 4661.2 4676.5 4687.1 4735.0 5642.1 5674.6 5691.6 5723.7 5727.1 3449.0 3851.7 3894.5 3927.2 3939.7 4824.6 5926.3 6041.8 6052.7 6057.6 3633.1 3941.2 3957.3 3979.8 3984.4 3979.0 3996.1 4000.1 4001.1 4003.9 3555.8 4097.3 4172.9 4434.4 4618.7 4821.3 5424.0 5701.9 5707.2 5724.3 1264.9 1277.9 1280.0 1347.1 1363.8 742.9 851.2 904.5 907.7 914.5 4674.4 4882.0 5103.2 5314.5 5323.8 4468.3 4504.6 5095.9 5892.6 5922.4 4536.6 5125.8 5376.0 5410.8 5546.7 2733.2 3122.6 3123.5 3137.2 3147.7 4985.1 5476.4 5854.5 6248.8 6308.7 4999.0 5004.6 6026.4 6119.6 6123.8 5035.4 6049.6 6137.8 6157.5 6223.6 5156.3 5410.9 5609.7 5934.6 6057.1 5235.0 5465.2 5906.3 5922.4 5953.9 5395.0 5541.5 5823.3 6210.7 6297.5 3087.4 3849.7 3889.4 3910.9 3950.3 4847.6 5072.3 5317.6 5986.5 5988.6 4752.3 5503.0 5746.8 5794.8 5817.0 4647.5 5126.0 5289.9 5692.4 5765.0 5189.4 5365.2 5381.2 5560.9 5699.2 5265.4 5393.8 5708.6 5727.6 5814.5 4883.0 5025.6 5594.7 5597.6 5655.8 09:25:53 INFO - PROCESS | 1673 | 09:25:53 INFO - PROCESS | 1673 | [#5] yui.html Cycles:180 Average:26382.05 Median:3853.57 stddev:43104.68 (1118.6%) stddev-sans-first:43182.15 09:25:53 INFO - PROCESS | 1673 | Values: 606.4 678.0 684.2 743.5 832.0 17210.8 17901.6 17930.4 17936.2 17940.4 16117.0 16253.4 16333.0 16352.8 16368.5 662.2 854.8 881.5 885.6 887.3 686.2 843.6 856.3 868.3 875.4 919.1 920.1 920.6 922.2 924.1 710.5 917.8 918.2 918.3 919.3 543.6 546.9 546.9 661.1 680.9 15469.4 15504.9 15509.7 15531.2 15537.5 14040.6 14294.5 14468.7 14513.6 14580.0 12977.4 13056.5 13076.3 13331.7 13661.3 3775.5 4471.4 4572.4 4662.4 4731.6 121582.4 123474.0 123498.4 126423.0 126634.0 8932.7 10926.9 11186.9 11507.4 11667.5 22780.4 23090.3 23122.8 23138.3 23153.5 6428.8 7752.2 7789.6 7972.1 7991.6 4299.0 4415.1 4431.5 4457.3 4460.9 2979.4 3802.2 3905.0 4104.9 4116.7 720.5 722.6 724.7 733.0 740.6 443.0 576.8 640.9 641.2 673.4 8560.7 8608.3 9000.8 9185.9 9290.6 519.3 539.7 579.9 586.3 607.7 4291.9 4547.7 4549.5 4551.1 4554.6 163992.2 164164.0 164272.2 165642.0 169521.0 99840.5 141617.0 141709.9 148055.3 150639.0 115359.0 121507.0 122792.4 123292.0 126197.7 8632.9 8735.7 8771.7 8897.3 9015.2 56978.7 57885.7 59996.5 61833.1 66654.3 84885.0 85571.6 86189.1 91910.1 93838.0 11609.5 12448.4 12754.5 13139.6 13342.4 14664.3 15025.6 15135.3 15356.8 15650.5 13675.6 13896.4 14115.8 14246.5 14401.9 13590.8 13665.7 13961.9 14084.6 14549.6 13142.7 13537.2 13624.1 13640.5 13723.9 11429.5 11489.3 11504.3 11664.2 11771.0 11224.9 11376.8 11435.4 11459.5 11492.7 09:25:53 INFO - PROCESS | 1673 | -------- Summary: end -------- 09:25:53 INFO - PROCESS | 1673 | 09:25:53 INFO - TEST-INFO | 1673: exit 0 09:25:53 INFO - TEST-OK | dromaeo_css | took 1118631ms 09:25:53 INFO - TEST-START | kraken 09:25:53 INFO - operating with platform_type : mac_ 09:25:53 INFO - Initialising browser for kraken test... 09:25:53 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/tmpRtb5xs/profile http://localhost:49269/getInfo.html) 09:25:56 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 09:25:56 INFO - PROCESS | 1679 | colorDepth:24 09:25:56 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 09:25:56 INFO - PROCESS | 1679 | __metrics 09:25:56 INFO - PROCESS | 1679 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:25:56 INFO - PROCESS | 1679 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:25:57 INFO - TEST-INFO | 1679: exit 0 09:25:57 INFO - Browser initialized. 09:25:57 INFO - Running cycle 1/1 for kraken test... 09:25:57 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/tmpRtb5xs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:26:09 INFO - PROCESS | 1680 | RSS: Main: 233525248 09:26:09 INFO - PROCESS | 1680 | 09:26:11 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection) 09:26:12 INFO - PROCESS | 1680 | RSS: Main: 292458496 09:26:12 INFO - PROCESS | 1680 | 09:26:15 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-dft) 09:26:15 INFO - PROCESS | 1680 | RSS: Main: 288059392 09:26:15 INFO - PROCESS | 1680 | 09:26:19 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-fft) 09:26:19 INFO - PROCESS | 1680 | RSS: Main: 376442880 09:26:19 INFO - PROCESS | 1680 | 09:26:21 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator) 09:26:22 INFO - PROCESS | 1680 | RSS: Main: 279773184 09:26:22 INFO - PROCESS | 1680 | 09:26:24 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:26:25 INFO - PROCESS | 1680 | RSS: Main: 283189248 09:26:25 INFO - PROCESS | 1680 | 09:26:27 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom) 09:26:28 INFO - PROCESS | 1680 | RSS: Main: 321626112 09:26:28 INFO - PROCESS | 1680 | 09:26:31 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate) 09:26:32 INFO - PROCESS | 1680 | RSS: Main: 323117056 09:26:32 INFO - PROCESS | 1680 | 09:26:34 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial) 09:26:35 INFO - PROCESS | 1680 | RSS: Main: 323297280 09:26:35 INFO - PROCESS | 1680 | 09:26:37 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:26:37 INFO - PROCESS | 1680 | RSS: Main: 296685568 09:26:37 INFO - PROCESS | 1680 | 09:26:39 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:26:40 INFO - PROCESS | 1680 | RSS: Main: 303538176 09:26:40 INFO - PROCESS | 1680 | 09:26:42 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:26:43 INFO - PROCESS | 1680 | RSS: Main: 304177152 09:26:43 INFO - PROCESS | 1680 | 09:26:46 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:26:46 INFO - PROCESS | 1680 | RSS: Main: 319721472 09:26:46 INFO - PROCESS | 1680 | 09:26:49 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:26:50 INFO - PROCESS | 1680 | RSS: Main: 315875328 09:26:50 INFO - PROCESS | 1680 | 09:26:51 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49269/tests/kraken/driver.html?testName=ai-astar) 09:26:52 INFO - PROCESS | 1680 | RSS: Main: 359694336 09:26:52 INFO - PROCESS | 1680 | 09:26:52 INFO - PROCESS | 1680 | __start_tp_report 09:26:52 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 09:26:52 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 09:26:52 INFO - PROCESS | 1680 | |i|pagename|runs| 09:26:52 INFO - PROCESS | 1680 | |0;ai-astar;113;112;129;112;112;112;113;111;112;111 09:26:52 INFO - PROCESS | 1680 | |1;audio-beat-detection;147;107;130;159;107;129;127;109;157;116 09:26:52 INFO - PROCESS | 1680 | |2;audio-dft;186;184;180;182;182;183;191;183;183;187 09:26:52 INFO - PROCESS | 1680 | |3;audio-fft;76;75;75;154;57;57;58;69;139;57 09:26:52 INFO - PROCESS | 1680 | |4;audio-oscillator;82;85;133;71;69;85;122;68;70;119 09:26:52 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;87;85;87;87;88;87;88;84;86;84 09:26:52 INFO - PROCESS | 1680 | |6;imaging-darkroom;94;105;141;95;93;93;93;95;93;94 09:26:52 INFO - PROCESS | 1680 | |7;imaging-desaturate;85;86;86;87;84;82;86;91;113;86 09:26:52 INFO - PROCESS | 1680 | |8;json-parse-financial;55;54;56;56;54;55;53;54;52;54 09:26:52 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;65;67;69;79;67;72;68;66;68;70 09:26:52 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;86;87;88;83;87;89;104;104;108;84 09:26:52 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;139;140;136;141;147;156;142;140;182;157 09:26:52 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;176;173;174;172;171;172;173;171;171;169 09:26:52 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;69;66;68;69;69;68;67;68;67;67 09:26:52 INFO - PROCESS | 1680 | __end_tp_report 09:26:52 INFO - PROCESS | 1680 | __start_cc_report 09:26:52 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,2065 09:26:52 INFO - PROCESS | 1680 | __end_cc_report 09:26:52 INFO - PROCESS | 1680 | __startTimestamp1472228812243__endTimestamp 09:26:52 INFO - PROCESS | 1680 | 09:26:52 INFO - PROCESS | 1680 | ------- Summary: start ------- 09:26:52 INFO - PROCESS | 1680 | Number of tests: 14 09:26:52 INFO - PROCESS | 1680 | 09:26:52 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:113.70 Median:112.00 stddev:5.42 (4.8%) stddev-sans-first:5.74 09:26:52 INFO - PROCESS | 1680 | Values: 113.0 112.0 129.0 112.0 112.0 112.0 113.0 111.0 112.0 111.0 09:26:52 INFO - PROCESS | 1680 | 09:26:52 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:128.80 Median:129.50 stddev:19.86 (15.3%) stddev-sans-first:19.94 09:26:52 INFO - PROCESS | 1680 | Values: 147.0 107.0 130.0 159.0 107.0 129.0 127.0 109.0 157.0 116.0 09:26:52 INFO - PROCESS | 1680 | 09:26:52 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:184.10 Median:183.50 stddev:3.14 (1.7%) stddev-sans-first:3.26 09:26:52 INFO - PROCESS | 1680 | Values: 186.0 184.0 180.0 182.0 182.0 183.0 191.0 183.0 183.0 187.0 09:26:52 INFO - PROCESS | 1680 | 09:26:52 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:81.70 Median:63.50 stddev:35.25 (55.5%) stddev-sans-first:37.33 09:26:52 INFO - PROCESS | 1680 | Values: 76.0 75.0 75.0 154.0 57.0 57.0 58.0 69.0 139.0 57.0 09:26:52 INFO - PROCESS | 1680 | 09:26:52 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:90.40 Median:70.50 stddev:24.75 (35.1%) stddev-sans-first:26.06 09:26:52 INFO - PROCESS | 1680 | Values: 82.0 85.0 133.0 71.0 69.0 85.0 122.0 68.0 70.0 119.0 09:26:52 INFO - PROCESS | 1680 | 09:26:52 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:1.49 (1.7%) stddev-sans-first:1.56 09:26:52 INFO - PROCESS | 1680 | Values: 87.0 85.0 87.0 87.0 88.0 87.0 88.0 84.0 86.0 84.0 09:26:52 INFO - PROCESS | 1680 | 09:26:52 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:99.60 Median:93.50 stddev:14.99 (16.0%) stddev-sans-first:15.76 09:26:52 INFO - PROCESS | 1680 | Values: 94.0 105.0 141.0 95.0 93.0 93.0 93.0 95.0 93.0 94.0 09:26:52 INFO - PROCESS | 1680 | 09:26:52 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:88.60 Median:86.00 stddev:8.87 (10.3%) stddev-sans-first:9.31 09:26:52 INFO - PROCESS | 1680 | Values: 85.0 86.0 86.0 87.0 84.0 82.0 86.0 91.0 113.0 86.0 09:26:52 INFO - PROCESS | 1680 | 09:26:52 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.50 stddev:1.25 (2.3%) stddev-sans-first:1.30 09:26:52 INFO - PROCESS | 1680 | Values: 55.0 54.0 56.0 56.0 54.0 55.0 53.0 54.0 52.0 54.0 09:26:52 INFO - PROCESS | 1680 | 09:26:52 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:68.50 stddev:4.01 (5.9%) stddev-sans-first:3.97 09:26:52 INFO - PROCESS | 1680 | Values: 65.0 67.0 69.0 79.0 67.0 72.0 68.0 66.0 68.0 70.0 09:26:52 INFO - PROCESS | 1680 | 09:26:52 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:92.00 Median:86.50 stddev:9.43 (10.9%) stddev-sans-first:9.75 09:26:52 INFO - PROCESS | 1680 | Values: 86.0 87.0 88.0 83.0 87.0 89.0 104.0 104.0 108.0 84.0 09:26:52 INFO - PROCESS | 1680 | 09:26:52 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:144.50 stddev:13.90 (9.6%) stddev-sans-first:14.36 09:26:52 INFO - PROCESS | 1680 | Values: 139.0 140.0 136.0 141.0 147.0 156.0 142.0 140.0 182.0 157.0 09:26:52 INFO - PROCESS | 1680 | 09:26:52 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.20 Median:172.50 stddev:1.93 (1.1%) stddev-sans-first:1.48 09:26:52 INFO - PROCESS | 1680 | Values: 176.0 173.0 174.0 172.0 171.0 172.0 173.0 171.0 171.0 169.0 09:26:52 INFO - PROCESS | 1680 | 09:26:52 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.03 (1.5%) stddev-sans-first:1.00 09:26:52 INFO - PROCESS | 1680 | Values: 69.0 66.0 68.0 69.0 69.0 68.0 67.0 68.0 67.0 67.0 09:26:52 INFO - PROCESS | 1680 | -------- Summary: end -------- 09:26:52 INFO - PROCESS | 1680 | 09:26:53 INFO - TEST-INFO | 1680: exit 0 09:26:53 INFO - TEST-OK | kraken | took 59324ms 09:26:53 INFO - SUITE-END | took 1177s 09:26:53 INFO - Completed test suite (00:19:38) 09:26:53 INFO - javascript benchmark 09:26:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3329.9001029969104, 3338.666133386661, 3345.648706885777, 3374.595048594169, 3415.897523074307, 5525.171224316355, 5630.042892708239, 5665.206871038058, 5689.914651280229, 5707.2009918611375, 5646.237757902682, 5667.716614169296, 5748.5401167906575, 5765.1928729977835, 5795.072788353864, 5045.722485263309, 5175.792968281283, 5184.559312458427, 5194.766235519392, 5305.4164041955355, 5297.920531192035, 5299.60252981026, 5304.973475132619, 5312.096943519592, 5336.813211537577, 5127.282180494733, 5167.48325167484, 5170.508801663836, 5181.585473162157, 5228.633995620308, 5196.960607878402, 5214.478552144793, 5216.217567364888, 5231.947680523184, 5231.95360927816, 5094.337736094284, 5138.0, 5166.9224961625605, 5187.144121219994, 5190.870228244286, 5557.3886872444, 5559.305086864142, 5601.887962240732, 5715.971420142914, 5761.539076873882, 6100.23747031621, 6143.1092491588915, 6167.506599472032, 6187.721552530147, 6202.069689546604, 5139.8458046258675, 6119.87760244797, 6145.064304744036, 6244.594101383395, 6285.779997700059, 5724.39893811148, 5753.942460575425, 5754.964106460793, 5776.56675749312, 5785.82642520725, 6257.405546473078, 6267.0, 6280.560360774702, 6302.149209856702, 6339.302676705559, 5848.970755146197, 5939.613925094855, 5940.316863560746, 6082.2701275847185, 6113.358097399798, 6089.269287685417, 6141.692915354214, 6146.846328841814, 6149.723262453201, 6165.753369865245, 6037.45662890342, 6134.681512609229, 6141.087130295746, 6183.134361189437, 6190.442860142519, 6107.6640784757265, 6182.969085154546, 6187.288461826947, 6189.659568723763, 6204.751809927552, 4908.631852610997, 4910.140725373046, 4920.581750551171, 4927.310176575283, 4962.578180854668, 4344.087741574304, 4353.1293741251875, 4392.9121417572105, 4416.6025057744955, 4456.041950980555, 5638.661680299195, 5644.040513112781, 5653.943460565424, 5656.349519805275, 5681.2330335404195, 6171.197744293213, 6175.783847565313, 6233.937660623426, 6271.122042913996, 6271.404216599416, 4437.534058923831, 5540.750666220029, 5591.10542313228, 5595.63628364155, 5671.064274394678, 5402.702851343214, 5413.052715774724, 5430.266913966643, 5434.619576629598, 5467.644603100785, 6267.561270711007, 6303.212098487689, 6305.991041433349, 6327.810165695037, 6332.240131184288, 6052.425019623129, 6114.816555503342, 6124.258880972441, 6249.718762655601, 6257.217847769029, 6226.750929962751, 6239.126522286974, 6271.153394291712, 6285.622862628257, 6307.3377295383325, 5940.376260492586, 5966.642001479751, 5973.880522389441, 6006.0, 6038.849028774316, 6075.513958883367, 6102.664353460602, 6109.419605137505, 6113.388661134029, 6144.938550614436, 5843.2695913010875, 6015.759369625166, 6016.759329626941, 6025.246844144482, 6050.334463209131, 5897.085951677497, 5914.852128696645, 5944.881102377842, 5974.671393073423, 6054.697265136813, 5801.82594522165, 5825.970870145622, 5838.5621078418435, 5841.9707901461925, 5842.941570584239, 5885.61743486672, 6008.339082700987, 6013.458788709037, 6022.5784195105925, 6027.427394397525, 5741.540676745767, 5747.9137812932, 5762.049261871744, 5790.623609465371, 5825.563082768894], "value": 5640.153540333354, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.46818499650385, 488.2554104989892, 526.7287347016285, 531.1899353485934, 536.32423146835, 31290.843545782296, 31971.840140799293, 33383.49924751131, 33393.16517087074, 33408.66591334089, 34037.48943765839, 39967.0, 41277.55528556494, 44356.0, 46529.83675408117, 9704.078112579293, 9848.0, 9851.261155413336, 9852.211823054138, 9882.90117098827, 9583.856242156373, 9670.468124253162, 9690.273229507815, 9713.95143024284, 9716.90283097167, 8787.0, 8925.687600933968, 12095.516179352815, 12372.319522426264, 12402.627921162379, 12381.93809030958, 12389.318587477685, 12389.566365177176, 12400.565980190695, 12440.937795311056, 8749.03760586335, 12049.03607711381, 12142.332171730552, 12144.817827732591, 12157.939210303892, 41157.38263926043, 41920.79039604813, 42047.789761051, 44161.33757993633, 44309.904360998255, 2727.972720272792, 3331.90047284398, 3377.4258376076127, 3596.4425514045365, 3758.9812050939845, 31311.84344078288, 31466.0, 31511.212219694462, 31584.684153158403, 31948.840255798805, 3141.575887255214, 3234.32079263355, 3269.9019029429155, 3328.5007248912616, 3345.7992520448606, 454245.0, 458746.0, 460245.0, 460543.0, 462569.9999999966, 62619.060714089326, 62873.0, 63006.0, 63039.6848015757, 63062.0, 55570.0, 64236.71526569536, 65318.67340663266, 65429.345706543274, 69371.0, 16294.022358658518, 16794.0, 16818.159092045593, 16835.831641683428, 16865.156742162842, 12944.0, 13044.282564458856, 13051.0, 13059.281739504419, 13070.673233169056, 15916.204189790464, 16267.674646507003, 18149.001804900858, 18217.817821821878, 18333.541661458305, 5949.940500595025, 6412.877746394455, 6525.151730275129, 6742.157230346207, 6798.932010679929, 847.9788005299916, 1019.0522813783252, 1019.37308555238, 1024.933379330341, 1068.2522234435771, 28581.285467863054, 29869.70130298669, 30056.54915176274, 30558.84720576383, 30845.0, 864.5115509737104, 1042.4475028235047, 1049.8110340138849, 1070.823314153156, 1121.5401685308984, 13161.60515184546, 15580.376784928703, 15809.36762529509, 15952.964038905624, 16043.679126417406, 562175.0, 566892.0, 567006.0, 567459.0, 568446.0, 46675.29987050197, 50121.24818127731, 59106.0, 59307.703461482415, 59514.404855951754, 1319.9274039927705, 1347.2590075458459, 1362.3528823808613, 1363.4137320951888, 1367.8974076944269, 30434.543481847348, 31537.8423107883, 31927.967571532223, 32085.03744887657, 32119.0, 50714.74642626763, 51086.0, 51152.23271650854, 51285.230721539956, 51289.0, 644245.0, 661646.0, 662350.0, 840628.0, 840764.0, 26026.089086881868, 26143.607845882707, 26243.60634590521, 26274.0, 26284.21147365582, 38732.61267387403, 42486.15027699367, 43900.780496097315, 46216.30675539937, 48071.75964120157, 35102.0, 37496.0, 38586.614133858304, 39438.0, 39902.60097399105, 36193.81903090468, 38283.61716382801, 38421.231575368896, 39110.804445977585, 39639.80180099196, 37892.810535947145, 38845.6115438842, 38958.4156237651, 39432.80283598564, 41135.0, 31465.68534314719, 33806.661933380354, 35239.64760352466, 35427.64572354243, 35439.82280088583, 25061.24816255515, 25509.489810203322, 25630.871845640653, 25649.743502564736, 26086.0], "value": 20168.018196196168, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.3591224191366, 365.65628309389183, 376.3658235872553, 378.6459660217696, 401.8854626431466, 15423.305951232194, 16333.428330008452, 19904.0, 20695.861727604977, 20827.791722082773, 19955.201791928306, 20483.385498435033, 20506.974651267414, 20518.38444846653, 21329.893350533224, 5223.503767142115, 5811.6803575803315, 5972.850678733023, 6073.362296958822, 6080.1183828344865, 4458.799354029076, 6031.577789554733, 6038.69806509675, 6039.879202415949, 6046.304674962392, 5693.943060569382, 5900.350961394243, 5976.581639285252, 5978.9701051494685, 5981.611195272315, 5924.348321684612, 5963.224780778514, 5965.0, 5967.075103358986, 5970.791022314198, 5036.597072234213, 5036.647434679574, 5188.11801993662, 5190.2993095932325, 5190.32525771651, 26406.6039009415, 26505.46989060208, 26583.601245981325, 26724.866375667996, 26823.0, 2269.4212975691257, 2295.3228797504776, 2344.10923848938, 2397.6523404106306, 2421.6609674645565, 15467.690646187013, 15956.4415245467, 16152.515424537283, 16294.83705162945, 16645.358352776148, 1873.4379686094117, 1878.690016147334, 1906.447130332202, 1926.7784204816346, 1995.4911497568173, 89022.5548872258, 115393.42303288514, 121769.0, 124076.0, 124465.37767311106, 10764.801727326349, 10978.066864316463, 10982.0, 11074.058705010164, 11301.26541774782, 13709.245991470365, 13744.793828092586, 13745.793813092812, 13748.862511374959, 13750.724985500234, 8431.494110353398, 8715.346349023848, 8744.344174186834, 8876.20114189726, 8880.245179159843, 7237.819054523679, 8798.86801697975, 8805.207531322212, 8816.162464565856, 9019.729408117766, 5728.885422291614, 6211.844703882349, 6634.568753031038, 6646.4350530205475, 6788.796336109925, 1813.9365122220659, 1837.9107527201822, 1858.8791728537872, 1862.3761040051734, 1898.9525261868287, 846.9830603388021, 929.697848199338, 955.9761005974767, 1044.1646682653848, 1048.3919326790597, 21128.471788205417, 21491.355259342246, 22074.44813879666, 22647.773522264895, 22721.659175112385, 911.7766147293955, 929.2658799548416, 941.1999800169801, 955.0, 1003.5132960514145, 9895.455749933823, 10438.008389203014, 10756.56973721058, 11729.941350293364, 11869.762604747857, 562876.0, 563602.0, 563757.0, 580282.0, 582483.0, 75589.0, 109702.0, 114508.0, 114875.0, 119581.40209299071, 950.5532399772096, 978.7357413498315, 993.7515621094726, 994.4294000009911, 996.8386829343733, 18448.63102737911, 19714.802851971297, 20150.0, 20163.0, 20239.0, 52931.6767080826, 53058.73470632622, 53487.73256133695, 53925.73037134789, 54495.455045450624, 64295.67852160709, 72931.17672058241, 76128.85806712793, 77893.61053194698, 81253.0, 7491.662875170739, 7859.842803143791, 7990.400719945911, 8156.796080098045, 8312.41813073079, 7265.164506081868, 10066.993300669874, 11376.886231137914, 12654.0, 13475.797863031867, 10988.615398461016, 11058.557657693602, 11298.209125361474, 11338.036266917405, 11343.0, 10854.29447085937, 10944.39805810688, 10951.780964380827, 11288.661340159808, 11453.48459319316, 9551.856722149034, 11304.717382065513, 11367.431628418712, 11619.593314233962, 11696.766064678828, 6476.449501792212, 7156.570605763671, 7358.11702595692, 7471.402287816853, 7627.694892204471, 9792.902070979198, 10078.748031298983, 10254.23093267993, 10992.835107473235, 11244.325340479598], "value": 9616.096415235212, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1513.3341329814884, 1518.3091693279555, 1710.6236627941848, 1852.1665250637216, 1877.6057028024113, 8434.746957591275, 9028.32287578431, 9034.096590340963, 9035.096490350961, 9041.095890410956, 7848.332891704212, 8101.5544145072, 8367.079621241659, 8720.34597405194, 8851.070637583041, 2112.640851055324, 2286.748457669655, 2346.120204923158, 2396.940076498093, 2423.5879900416885, 2344.601417758985, 2348.894299756515, 2399.676043734091, 2402.1112188490197, 2404.0, 2368.301351101429, 2383.30884043628, 2387.1286980252257, 2402.1832576923844, 2410.855348679076, 2330.8135349172026, 2354.1407386303963, 2355.552445035438, 2358.5518751437257, 2377.0, 2323.5236776460783, 2352.1061996441413, 2352.7059117610297, 2360.3627020704484, 2367.2188177901426, 8694.826103477959, 8711.912880871174, 8794.296456283546, 8797.912020879838, 8798.340124490689, 5263.236830659533, 5418.051840927822, 5422.728863556846, 5427.728613569345, 5440.700761458118, 3618.475321078429, 3651.7261205409436, 3687.3731465650903, 3720.627937206285, 3742.6257374262627, 1086.9293495922739, 1096.5778175402447, 1099.5326986030905, 1106.7675788084512, 1118.703543560957, 11360.204785664924, 11514.251573647685, 11596.536138554447, 11605.129615278887, 11606.0, 4554.20301447252, 4590.449146102489, 4810.302506136591, 4816.20532612115, 4851.684640498356, 9947.900520994843, 9961.203103751683, 9961.601535938626, 9962.701118966443, 9979.0, 5329.733513324319, 5333.146696528537, 5339.973300133553, 5345.1714984177515, 5356.143017117242, 3892.9416058759143, 4337.652987760972, 4493.078918821634, 4821.831235906727, 4871.123197824426, 3229.4671379222164, 3915.099527108781, 4005.9799701001307, 4026.7181297308907, 4053.3717273822604, 1265.7278685082752, 1293.5020017293223, 1294.3140135728079, 1302.6743314171458, 1312.7637025335532, 837.2046555772041, 845.7166849105496, 886.1847100667402, 893.2585953658448, 931.334096121263, 5458.45415458459, 5694.914576281359, 5726.9713651431475, 5858.47273745365, 5875.265591801025, 903.1555495611624, 903.4443817052597, 903.9683611073713, 913.6162811619135, 920.1672486399818, 3710.0910276982313, 3763.379042457964, 3765.755225910307, 3813.866514671973, 3864.9227015459533, 10392.168626509861, 10646.787064258826, 10777.137828973664, 10777.299475534066, 10777.353358798497, 10660.0, 10675.893241067646, 10687.14502839771, 10698.465076746288, 10708.250422470508, 6915.688794004282, 8059.274665280153, 8115.837683246302, 8184.918150818654, 8213.671453141927, 5324.281222035131, 5563.833085007456, 5599.468050535193, 5630.7466164023535, 5686.0, 7992.0, 9790.951045244728, 9962.651307204213, 9985.700428987142, 10045.899541004497, 9773.169280611228, 10064.0, 10252.333598316085, 10364.740881478023, 10391.896081039395, 6485.708143133476, 6693.866122677617, 6833.760818371333, 6970.059042029261, 7125.714971401293, 8133.0646975598565, 8471.830563388821, 8480.83038339242, 8486.0, 8487.236148746642, 6140.3552626975315, 6245.531585131042, 7513.774586762406, 7586.696532138652, 7604.5437273763755, 7295.0, 7340.11918569775, 7377.668004939684, 7393.112826460859, 7423.146338170964, 6752.324767523209, 6795.864082718417, 6889.448844092361, 6902.378785909293, 6908.2400935896, 4756.524347565216, 4792.784324705327, 4825.445073816556, 4842.975785121052, 4935.605151587936, 4228.5982831630945, 4737.0525894820485, 4816.494268101799, 4894.094592500399, 5003.124453220599], "value": 4539.494109309638, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.8763813017598, 300.11915029388746, 309.26703712202084, 311.6711868978227, 311.8877204206485, 4560.6123479504195, 4898.779554920024, 5539.667619942807, 5543.362513310965, 5583.581231407646, 5149.382074151098, 5174.275601415805, 5298.046351656701, 5430.995265875806, 5517.200005999128, 3392.8473218705158, 3882.903232254579, 4054.209429161315, 4118.341065429532, 4153.380181729128, 3581.3553560359123, 3795.7532760370627, 4167.708260421772, 4176.97911510442, 4194.78351278129, 3447.913802154941, 4016.919661606766, 4068.9186216275657, 4086.3257562502154, 4134.627883490485, 4021.738586991836, 4096.8566100186645, 4098.241825262336, 4114.362273847559, 4116.197341518405, 3536.169000284931, 3543.5216245806746, 3554.8578056877695, 3565.554305711786, 3571.5178450909057, 3748.475213470116, 4686.812527498896, 4996.825745949699, 5189.379363421485, 5248.921266181011, 4180.453232304037, 4661.160991021615, 4676.456769266134, 4687.086018226447, 4735.0, 5642.125470552071, 5674.631148975303, 5691.577105723569, 5723.656580605177, 5727.140928860663, 3448.9655103449145, 3851.656645017295, 3894.5131858517684, 3927.175293188434, 3939.72421930465, 4824.565789078964, 5926.318473375531, 6041.788537401214, 6052.727627256784, 6057.606255593417, 3633.0554055945645, 3941.187053955213, 3957.2679054375035, 3979.8209080590864, 3984.4222587724616, 3979.010494752624, 3996.100877302642, 4000.119973605802, 4001.139754952641, 4003.939940900889, 3555.839987200582, 4097.344424892062, 4172.853950111731, 4434.401355816988, 4618.676692631483, 4821.252705830602, 5423.9694458052845, 5701.914471282934, 5707.200991861143, 5724.313082430135, 1264.9430775614935, 1277.923324600527, 1280.0271793436868, 1347.1445632023735, 1363.754524185656, 742.8774252248427, 851.2126283188027, 904.5206040798388, 907.6732376344511, 914.533587870191, 4674.368960190399, 4881.999190166079, 5103.234514822732, 5314.5216930476445, 5323.813666521653, 4468.285074388083, 4504.594586487232, 5095.898082038412, 5892.616979896293, 5922.371347879368, 4536.6143877770755, 5125.769340379692, 5375.951689420525, 5410.83767486976, 5546.667199968015, 2733.248356701923, 3122.625284965773, 3123.4846250368887, 3137.231378312328, 3147.68523147688, 4985.127602669519, 5476.397596264408, 5854.511832471183, 6248.75004999804, 6308.653024083627, 4998.9500104999215, 5004.648744838873, 6026.427489388501, 6119.602225855305, 6123.8162855114415, 5035.446100928895, 6049.606775559662, 6137.815865524041, 6157.491414603451, 6223.603912493632, 5156.278121062979, 5410.918836217538, 5609.747561359667, 5934.635033942303, 6057.121717350871, 5235.01069593552, 5465.180222966586, 5906.320773110975, 5922.378150294157, 5953.910691339547, 5394.974954758583, 5541.469257257645, 5823.272090988627, 6210.720517576629, 6297.496200304056, 3087.3516561522333, 3849.653531182207, 3889.444222311098, 3910.941335879907, 3950.30869597825, 4847.61219102478, 5072.315237442898, 5317.627766056494, 5986.461218490357, 5988.610740301867, 4752.310914917387, 5503.009458297545, 5746.770129194785, 5794.768209271582, 5816.952948469316, 4647.4655414626395, 5126.0, 5289.8677533060445, 5692.402297758841, 5764.962306784819, 5189.429162792015, 5365.248865158803, 5381.246625472359, 5560.942451293047, 5699.230603868425, 5265.368155821173, 5393.83818485446, 5708.628939118944, 5727.599068065362, 5814.505767009682, 4882.975585122052, 5025.623078269217, 5594.748236329294, 5597.63615365, 5655.7840064386355], "value": 4108.359358098345, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.3724045612792, 678.0405725897855, 684.185818875538, 743.5315751076821, 831.9625616847242, 17210.827891721077, 17901.552461188476, 17930.372436964677, 17936.19287132079, 17940.372086976975, 16117.032978021298, 16253.431129910456, 16333.0, 16352.83647163528, 16368.508944731677, 662.2185820731535, 854.8162145138779, 881.5371929736875, 885.579349808842, 887.2901678657081, 686.2279935073043, 843.6119385082869, 856.327782690588, 868.3140319147866, 875.3566128895256, 919.0855099176296, 920.1442658327752, 920.5949382271813, 922.1608336413865, 924.0713083351222, 710.523948954202, 917.7751450894507, 918.1598837064079, 918.274563095154, 919.2737737187614, 543.6330476928098, 546.8742189296445, 546.9206964990094, 661.1174082599736, 680.8978653202009, 15469.381224750996, 15504.922475387664, 15509.689806203813, 15531.223438828127, 15537.533873983686, 14040.648983775385, 14294.499692510712, 14468.710625787426, 14513.637159071002, 14579.979401441904, 12977.35113244334, 13056.543020994219, 13076.280804555745, 13331.733365332737, 13661.316934153252, 3775.4714339992424, 4471.418715566955, 4572.428446444194, 4662.370579971728, 4731.597814185764, 121582.39208803899, 123473.9999999991, 123498.38250808688, 126423.0, 126634.0, 8932.732018039469, 10926.890731092746, 11186.888131118585, 11507.424628768527, 11667.474963626488, 22780.43048923757, 23090.307290781304, 23122.768772312396, 23138.3058508245, 23153.53692926132, 6428.8071357859335, 7752.18602046777, 7789.649465774054, 7972.1265502608, 7991.64037618297, 4299.0, 4415.116976604692, 4431.534688857688, 4457.331400289982, 4460.910781784346, 2979.4190132923873, 3802.1825307558456, 3904.9609503905167, 4104.897377565585, 4116.711830171919, 720.5064530796421, 722.5809030762146, 724.6521669598517, 732.9926700733031, 740.6000759589868, 442.9667774916894, 576.847135509082, 640.8846407646575, 641.214512222533, 673.4242222899351, 8560.743034055728, 8608.34145488165, 9000.848007183198, 9185.908140918638, 9290.581923813445, 519.3274709251529, 539.6788910598187, 579.9159121927381, 586.2876604925036, 607.6756406744221, 4291.935620965688, 4547.658925580595, 4549.476810166807, 4551.135284295973, 4554.590086892195, 163992.18003909904, 164164.0, 164272.17863910605, 165642.0, 169521.0, 99840.500797497, 141617.0, 141709.8743518848, 148055.25972370285, 150639.0, 115359.0, 121507.0, 122792.38603807102, 123292.0, 126197.73802261859, 8632.870506942276, 8735.737927862174, 8771.736847894574, 8897.332700047651, 9015.233705135137, 56978.715106424206, 57885.71057144687, 59996.50008749642, 61833.072503913376, 66654.33345666481, 84885.0, 85571.57214213889, 86189.1381086181, 91910.08089919016, 93838.0, 11609.47757350938, 12448.439820207932, 12754.48982040745, 13139.560417623023, 13342.399592018577, 14664.3401046951, 15025.624359390666, 15135.318910648826, 15356.846431535541, 15650.530484085495, 13675.589732308046, 13896.37466313998, 14115.788263175855, 14246.501372452329, 14401.855981440052, 13590.796138058135, 13665.726685466434, 13961.860381396056, 14084.647883802987, 14549.636259093608, 13142.737145257233, 13537.187768733513, 13624.114432561852, 13640.522581709592, 13723.931380343034, 11429.542818287175, 11489.342722772059, 11504.252223605774, 11664.183507154417, 11771.0, 11224.887751122384, 11376.805842196885, 11435.371054592077, 11459.541618335174, 11492.712682182679], "value": 7987.278964747372, "unit": "score"}], "name": "dromaeo_css", "value": 7390.589698258213, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 129.0, 112.0, 112.0, 112.0, 113.0, 111.0, 112.0, 111.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 107.0, 130.0, 159.0, 107.0, 129.0, 127.0, 109.0, 157.0, 116.0], "value": 128.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 180.0, 182.0, 182.0, 183.0, 191.0, 183.0, 183.0, 187.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 154.0, 57.0, 57.0, 58.0, 69.0, 139.0, 57.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 133.0, 71.0, 69.0, 85.0, 122.0, 68.0, 70.0, 119.0], "value": 90.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 87.0, 88.0, 87.0, 88.0, 84.0, 86.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 105.0, 141.0, 95.0, 93.0, 93.0, 93.0, 95.0, 93.0, 94.0], "value": 99.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 86.0, 87.0, 84.0, 82.0, 86.0, 91.0, 113.0, 86.0], "value": 88.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 56.0, 56.0, 54.0, 55.0, 53.0, 54.0, 52.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 69.0, 79.0, 67.0, 72.0, 68.0, 66.0, 68.0, 70.0], "value": 69.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 88.0, 83.0, 87.0, 89.0, 104.0, 104.0, 108.0, 84.0], "value": 92.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 136.0, 141.0, 147.0, 156.0, 142.0, 140.0, 182.0, 157.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 173.0, 174.0, 172.0, 171.0, 172.0, 173.0, 171.0, 171.0, 169.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 68.0, 69.0, 69.0, 68.0, 67.0, 68.0, 67.0, 67.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1476.6, "alertThreshold": 2.0}]} 09:26:53 INFO - Return code: 0 09:26:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:26:53 INFO - # TBPL SUCCESS # 09:26:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:26:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:26:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:26:53 INFO - 2016-08-26 09:26:53.601 screenresolution[1684:10507] starting screenresolution argv=screenresolution get 09:26:53 INFO - 2016-08-26 09:26:53.603 screenresolution[1684:10507] Display 0: 1600x1200x32@60 09:26:53 INFO - 2016-08-26 09:26:53.609 screenresolution[1685:10510] starting screenresolution argv=screenresolution list 09:26:53 INFO - Available Modes on Display 0 09:26:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:26:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:26:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:26:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:26:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:26:53 INFO - Intel Iris: 09:26:53 INFO - Chipset Model: Intel Iris 09:26:53 INFO - Type: GPU 09:26:53 INFO - Bus: Built-In 09:26:53 INFO - VRAM (Dynamic, Max): 1536 MB 09:26:53 INFO - Vendor: Intel (0x8086) 09:26:53 INFO - Device ID: 0x0a2e 09:26:53 INFO - Revision ID: 0x0009 09:26:53 INFO - Displays: 09:26:53 INFO - Display: 09:26:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:26:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:26:53 INFO - Display Serial Number: 1600x1200 60 09:26:53 INFO - Main Display: Yes 09:26:53 INFO - Mirror: Off 09:26:53 INFO - Online: Yes 09:26:53 INFO - Rotation: Supported 09:26:53 INFO - Return code: 0 09:26:53 INFO - Running post-action listener: _resource_record_post_action 09:26:53 INFO - [mozharness: 2016-08-26 16:26:53.842814Z] Finished run-tests step (success) 09:26:53 INFO - Running post-run listener: _resource_record_post_run 09:26:53 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 31845376; Write bytes: 706461696; Read time: 197; Write time: 1979 09:26:53 INFO - TinderboxPrint: CPU usage
53.3% 09:26:53 INFO - TinderboxPrint: I/O read bytes / time
31,845,376 / 197 09:26:53 INFO - TinderboxPrint: I/O write bytes / time
706,461,696 / 1,979 09:26:53 INFO - TinderboxPrint: CPU idle
2,268.5 (46.7%) 09:26:53 INFO - TinderboxPrint: CPU system
963.8 (19.9%) 09:26:53 INFO - TinderboxPrint: CPU user
1,621.4 (33.4%) 09:26:53 INFO - TinderboxPrint: Swap in / out
433,979,392 / 0 09:26:53 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 400742912; Write bytes: 394221056; Read time: 29588; Write time: 785 09:26:53 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 29772800; Write bytes: 297486848; Read time: 178; Write time: 1152 09:26:54 INFO - Running post-run listener: _upload_blobber_files 09:26:54 INFO - Blob upload gear active. 09:26:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:26:54 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 09:26:54 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'] 09:26:54 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 09:26:54 INFO - (blobuploader) - INFO - Open directory for files ... 09:26:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:26:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:26:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:26:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:26:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:26:55 INFO - (blobuploader) - INFO - Done attempting. 09:26:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:26:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:26:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:26:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:26:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:26:56 INFO - (blobuploader) - INFO - Done attempting. 09:26:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:26:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:26:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:26:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:26:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:26:59 INFO - (blobuploader) - INFO - Done attempting. 09:26:59 INFO - (blobuploader) - INFO - Iteration through files over. 09:26:59 INFO - Return code: 0 09:26:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:26:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:26:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/932df3bdc57b4b38c4dde9b65cca44e3f48556be8704c0946af4b114474c05ec6778bd50a842180e136df506b985891c0b623924cceeadd31d3d0df461c413ce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ea90a98335f3976601e088a74b97c4b8c12433ebe94b90124dfabc95221308110f7e1441e5da512a8b6c21b6f2a7f9af158afe393ff42290ab2954d1ee642f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2da213941a7fc303fb94d2578d6f8ad07366cddda4db1f42fb4eb6413a368d0ac6962b8aa9c18ba1203e6377f108d75941f48fc2c4086fd12d921d989193100a"} 09:26:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:26:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:26:59 INFO - Contents: 09:26:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/932df3bdc57b4b38c4dde9b65cca44e3f48556be8704c0946af4b114474c05ec6778bd50a842180e136df506b985891c0b623924cceeadd31d3d0df461c413ce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ea90a98335f3976601e088a74b97c4b8c12433ebe94b90124dfabc95221308110f7e1441e5da512a8b6c21b6f2a7f9af158afe393ff42290ab2954d1ee642f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2da213941a7fc303fb94d2578d6f8ad07366cddda4db1f42fb4eb6413a368d0ac6962b8aa9c18ba1203e6377f108d75941f48fc2c4086fd12d921d989193100a"} 09:26:59 INFO - Running post-run listener: copy_logs_to_upload_dir 09:26:59 INFO - Copying logs to upload dir... 09:26:59 INFO - mkdir: /builds/slave/test/build/upload/logs 09:26:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.472438 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-26 09:26:59.369421) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 09:26:59.374370) ========= 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.39CNihEE1A/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.tLsJcOGIHI/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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/932df3bdc57b4b38c4dde9b65cca44e3f48556be8704c0946af4b114474c05ec6778bd50a842180e136df506b985891c0b623924cceeadd31d3d0df461c413ce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ea90a98335f3976601e088a74b97c4b8c12433ebe94b90124dfabc95221308110f7e1441e5da512a8b6c21b6f2a7f9af158afe393ff42290ab2954d1ee642f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2da213941a7fc303fb94d2578d6f8ad07366cddda4db1f42fb4eb6413a368d0ac6962b8aa9c18ba1203e6377f108d75941f48fc2c4086fd12d921d989193100a"} build_url:https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010657 build_url: 'https://queue.taskcluster.net/v1/task/bryDVsZDR2-hiw9biDFKow/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/932df3bdc57b4b38c4dde9b65cca44e3f48556be8704c0946af4b114474c05ec6778bd50a842180e136df506b985891c0b623924cceeadd31d3d0df461c413ce", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ea90a98335f3976601e088a74b97c4b8c12433ebe94b90124dfabc95221308110f7e1441e5da512a8b6c21b6f2a7f9af158afe393ff42290ab2954d1ee642f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2da213941a7fc303fb94d2578d6f8ad07366cddda4db1f42fb4eb6413a368d0ac6962b8aa9c18ba1203e6377f108d75941f48fc2c4086fd12d921d989193100a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 09:26:59.407165) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 09:26:59.407488) ========= 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.39CNihEE1A/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.tLsJcOGIHI/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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.005128 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 09:26:59.456281) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 09:26:59.456615) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 09:26:59.456980) ========= ========= Total master_lag: 0.21 =========