builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0129 starttime: 1469164854.66 results: success (0) buildid: 20160721210813 builduid: 60b0b39b32bf49a18cfab6346fc8b3bc revision: 7f0562848c05433431f3c703edd151f24498a99f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 22:20:54.665377) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 22:20:54.665876) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 22:20:54.666186) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 22:20:54.795807) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 22:20:54.796102) ========= 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.JRnS1oRoLy/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.rGxSuAmlwC/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-21 22:20:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 214M=0s 2016-07-21 22:20:54 (214 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.142734 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 22:20:54.981067) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 22:20:54.981341) ========= 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.JRnS1oRoLy/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.rGxSuAmlwC/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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.026646 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 22:20:55.065308) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 22:20:55.065755) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 7f0562848c05433431f3c703edd151f24498a99f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 7f0562848c05433431f3c703edd151f24498a99f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JRnS1oRoLy/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.rGxSuAmlwC/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-21 22:20:55,144 truncating revision to first 12 chars 2016-07-21 22:20:55,144 Setting DEBUG logging. 2016-07-21 22:20:55,144 attempt 1/10 2016-07-21 22:20:55,144 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/7f0562848c05?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 22:20:56,255 unpacking tar archive at: autoland-7f0562848c05/testing/mozharness/ program finished with exit code 0 elapsedTime=1.390049 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-21 22:20:56.479789) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 22:20:56.480122) ========= script_repo_revision: 7f0562848c05433431f3c703edd151f24498a99f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 22:20:56.480505) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 22:20:56.480767) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 22:20:56.502586) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-07-21 22:20:56.502941) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JRnS1oRoLy/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.rGxSuAmlwC/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 22:20:56 INFO - MultiFileLogger online at 20160721 22:20:56 in /builds/slave/test 22:20:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 22:20:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:20:56 INFO - {'append_to_log': False, 22:20:56 INFO - 'base_work_dir': '/builds/slave/test', 22:20:56 INFO - 'blob_upload_branch': 'Autoland', 22:20:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:20:56 INFO - 'branch': 'Autoland', 22:20:56 INFO - 'buildbot_json_path': 'buildprops.json', 22:20:56 INFO - 'config_files': ('talos/mac_config.py',), 22:20:56 INFO - 'default_actions': ('clobber', 22:20:56 INFO - 'read-buildbot-config', 22:20:56 INFO - 'download-and-extract', 22:20:56 INFO - 'populate-webroot', 22:20:56 INFO - 'create-virtualenv', 22:20:56 INFO - 'install', 22:20:56 INFO - 'run-tests'), 22:20:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:20:56 INFO - 'download_minidump_stackwalk': True, 22:20:56 INFO - 'download_symbols': 'ondemand', 22:20:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:20:56 INFO - 'tooltool.py': '/tools/tooltool.py', 22:20:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:20:56 INFO - '/tools/misc-python/virtualenv.py')}, 22:20:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:20:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:20:56 INFO - 'installer_path': 'installer.exe', 22:20:56 INFO - 'log_level': 'info', 22:20:56 INFO - 'log_name': 'talos', 22:20:56 INFO - 'log_to_console': True, 22:20:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:20:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:20:56 INFO - 'opt_config_files': (), 22:20:56 INFO - 'pip_index': False, 22:20:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:20:56 INFO - 'cmd': ('bash', 22:20:56 INFO - '-c', 22:20:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:20:56 INFO - 'enabled': True, 22:20:56 INFO - 'halt_on_failure': False, 22:20:56 INFO - 'name': 'check_screen_resolution'},), 22:20:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:20:56 INFO - 'cmd': ('bash', 22:20:56 INFO - '-c', 22:20:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:20:56 INFO - 'enabled': True, 22:20:56 INFO - 'halt_on_failure': False, 22:20:56 INFO - 'name': 'check_screen_resolution'},), 22:20:56 INFO - 'run_cmd_checks_enabled': True, 22:20:56 INFO - 'sps_profile': False, 22:20:56 INFO - 'sps_profile_interval': 0, 22:20:56 INFO - 'suite': 'dromaeojs', 22:20:56 INFO - 'system_bits': '32', 22:20:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:20:56 INFO - 'title': 't-yosemite-r7-0129', 22:20:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:20:56 INFO - 'use_talos_json': True, 22:20:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:20:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:20:56 INFO - 'work_dir': 'build'} 22:20:56 INFO - [mozharness: 2016-07-22 05:20:56.701284Z] Running clobber step. 22:20:56 INFO - Running pre-action listener: _resource_record_pre_action 22:20:56 INFO - Running main action method: clobber 22:20:56 INFO - rmtree: /builds/slave/test/build 22:20:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:20:58 INFO - Running post-action listener: _resource_record_post_action 22:20:58 INFO - [mozharness: 2016-07-22 05:20:58.885883Z] Finished clobber step (success) 22:20:58 INFO - [mozharness: 2016-07-22 05:20:58.886014Z] Running read-buildbot-config step. 22:20:58 INFO - Running pre-action listener: _resource_record_pre_action 22:20:58 INFO - Running main action method: read_buildbot_config 22:20:58 INFO - Using buildbot properties: 22:20:58 INFO - { 22:20:58 INFO - "project": "", 22:20:58 INFO - "product": "firefox", 22:20:58 INFO - "script_repo_revision": "production", 22:20:58 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 22:20:58 INFO - "repository": "", 22:20:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 22:20:58 INFO - "buildid": "20160721210813", 22:20:58 INFO - "pgo_build": "False", 22:20:58 INFO - "basedir": "/builds/slave/test", 22:20:58 INFO - "buildnumber": 24, 22:20:58 INFO - "slavename": "t-yosemite-r7-0129", 22:20:58 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 22:20:58 INFO - "platform": "yosemite_r7", 22:20:58 INFO - "scheduler": "tests-autoland-macosx64-talos", 22:20:58 INFO - "branch": "autoland", 22:20:58 INFO - "repo_path": "integration/autoland", 22:20:58 INFO - "revision": "7f0562848c05433431f3c703edd151f24498a99f", 22:20:58 INFO - "stage_platform": "macosx64", 22:20:58 INFO - "builduid": "60b0b39b32bf49a18cfab6346fc8b3bc", 22:20:58 INFO - "slavebuilddir": "test" 22:20:58 INFO - } 22:20:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 22:20:58 INFO - Running post-action listener: _resource_record_post_action 22:20:58 INFO - [mozharness: 2016-07-22 05:20:58.889412Z] Finished read-buildbot-config step (success) 22:20:58 INFO - [mozharness: 2016-07-22 05:20:58.889559Z] Running download-and-extract step. 22:20:58 INFO - Running pre-action listener: _resource_record_pre_action 22:20:58 INFO - Running main action method: download_and_extract 22:20:58 INFO - mkdir: /builds/slave/test/build/tests 22:20:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:20:58 INFO - https://queue.taskcluster.net/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:20:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 22:20:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 22:20:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 22:20:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 22:21:07 INFO - Downloaded 1199 bytes. 22:21:07 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 22:21:07 INFO - Using the following test package requirements: 22:21:07 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 22:21:07 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:21:07 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 22:21:07 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:21:07 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 22:21:07 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:21:07 INFO - u'jsshell-mac.zip'], 22:21:07 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:21:07 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 22:21:07 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 22:21:07 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:21:07 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 22:21:07 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:21:07 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 22:21:07 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:21:07 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 22:21:07 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 22:21:07 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 22:21:07 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:07 INFO - https://queue.taskcluster.net/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:21:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:21:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:21:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:21:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:21:11 INFO - Downloaded 18806755 bytes. 22:21:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:21:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:21:14 INFO - Return code: 0 22:21:14 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:21:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:14 INFO - https://queue.taskcluster.net/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:21:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:21:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:21:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 22:21:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:21:16 INFO - Downloaded 18806755 bytes. 22:21:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:21:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:21:20 INFO - Return code: 0 22:21:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:20 INFO - https://queue.taskcluster.net/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:21:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 22:21:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 22:21:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 22:21:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:21:21 INFO - Downloaded 14403289 bytes. 22:21:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:21:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:21:21 INFO - Return code: 0 22:21: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:21 INFO - https://queue.taskcluster.net/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:21:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:21:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:21:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 22:21:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 22:21:25 INFO - Downloaded 112793080 bytes. 22:21:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:21:25 INFO - mkdir: /builds/slave/test/properties 22:21:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:21:25 INFO - Writing to file /builds/slave/test/properties/build_url 22:21:25 INFO - Contents: 22:21:25 INFO - build_url:https://queue.taskcluster.net/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 22:21:26 INFO - Running post-action listener: _resource_record_post_action 22:21:26 INFO - Running post-action listener: set_extra_try_arguments 22:21:26 INFO - [mozharness: 2016-07-22 05:21:26.943743Z] Finished download-and-extract step (success) 22:21:26 INFO - [mozharness: 2016-07-22 05:21:26.943931Z] Running populate-webroot step. 22:21:26 INFO - Running pre-action listener: _resource_record_pre_action 22:21:26 INFO - Running main action method: populate_webroot 22:21:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:21:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:21:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:21:26 INFO - '--tppagecycles', 22:21:26 INFO - '7'], 22:21:26 INFO - 'tests': ['tsvgm']}}, 22:21:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:21:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:21:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:21:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:21:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:26 INFO - 'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:21:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:21:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:26 INFO - 'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['damp', 'tps']}, 22:21:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:26 INFO - 'tests': ['damp', 'tps']}, 22:21:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['dromaeo_dom']}, 22:21:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:21:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['basic_compositor_video']}, 22:21:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:21:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['a11yr', 22:21:26 INFO - 'ts_paint', 22:21:26 INFO - 'tpaint', 22:21:26 INFO - 'sessionrestore', 22:21:26 INFO - 'sessionrestore_no_auto_restore', 22:21:26 INFO - 'tabpaint']}, 22:21:26 INFO - 'other-e10s': {'tests': ['a11yr', 22:21:26 INFO - 'ts_paint', 22:21:26 INFO - 'tpaint', 22:21:26 INFO - 'sessionrestore', 22:21:26 INFO - 'sessionrestore_no_auto_restore', 22:21:26 INFO - 'tabpaint']}, 22:21:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:21:26 INFO - 'ts_paint', 22:21:26 INFO - 'tpaint', 22:21:26 INFO - 'sessionrestore', 22:21:26 INFO - 'sessionrestore_no_auto_restore', 22:21:26 INFO - 'tabpaint']}, 22:21:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:21:26 INFO - 'ts_paint', 22:21:26 INFO - 'tpaint', 22:21:26 INFO - 'sessionrestore', 22:21:26 INFO - 'sessionrestore_no_auto_restore', 22:21:26 INFO - 'tabpaint']}, 22:21:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['a11yr', 22:21:26 INFO - 'ts_paint', 22:21:26 INFO - 'tpaint', 22:21:26 INFO - 'sessionrestore', 22:21:26 INFO - 'sessionrestore_no_auto_restore', 22:21:26 INFO - 'tabpaint']}, 22:21:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['a11yr', 22:21:26 INFO - 'ts_paint', 22:21:26 INFO - 'tpaint', 22:21:26 INFO - 'sessionrestore', 22:21:26 INFO - 'sessionrestore_no_auto_restore', 22:21:26 INFO - 'tabpaint']}, 22:21:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['tsvgx', 22:21:26 INFO - 'tsvgr_opacity', 22:21:26 INFO - 'tart', 22:21:26 INFO - 'tscrollx', 22:21:26 INFO - 'cart']}, 22:21:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:21:26 INFO - 'tsvgr_opacity', 22:21:26 INFO - 'tart', 22:21:26 INFO - 'tscrollx', 22:21:26 INFO - 'cart']}, 22:21:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:26 INFO - 'talos_options': ['--disable-e10s'], 22:21:26 INFO - 'tests': ['tp5o']}, 22:21:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:26 INFO - 'tests': ['tp5o']}, 22:21:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:26 INFO - 'talos_options': ['--disable-e10s', 22:21:26 INFO - '--xperf_path', 22:21:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:21:26 INFO - 'tests': ['tp5n']}, 22:21:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:21:26 INFO - 'talos_options': ['--xperf_path', 22:21:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:21:26 INFO - 'tests': ['tp5n']}}, 22:21:26 INFO - 'talos.zip': {'path': '', 22:21:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:21:26 INFO - Running post-action listener: _resource_record_post_action 22:21:26 INFO - [mozharness: 2016-07-22 05:21:26.963146Z] Finished populate-webroot step (success) 22:21:26 INFO - [mozharness: 2016-07-22 05:21:26.963261Z] Running create-virtualenv step. 22:21:26 INFO - Running pre-action listener: _resource_record_pre_action 22:21:26 INFO - Running main action method: create_virtualenv 22:21:26 INFO - Creating virtualenv /builds/slave/test/build/venv 22:21:26 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 22:21:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:21:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:21:27 INFO - Using real prefix '/tools/python27' 22:21:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:21:27 INFO - Installing distribute.............................................................................................................................................................................................done. 22:21:30 INFO - Installing pip.................done. 22:21:30 INFO - Return code: 0 22:21:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:21:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:30 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 0x10791ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079b0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4184c9170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10787fd30>, '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 0x1075e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107877b20>, '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.JRnS1oRoLy/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.rGxSuAmlwC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 22:21:30 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 22:21:30 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 22:21:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRnS1oRoLy/Render', 22:21:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:30 INFO - 'HOME': '/Users/cltbld', 22:21:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:30 INFO - 'LOGNAME': 'cltbld', 22:21:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:30 INFO - 'MOZ_NO_REMOTE': '1', 22:21:30 INFO - 'NO_EM_RESTART': '1', 22:21:30 INFO - 'PAGER': '/bin/cat', 22:21:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:30 INFO - 'PWD': '/builds/slave/test', 22:21:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:30 INFO - 'SHELL': '/bin/bash', 22:21:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rGxSuAmlwC/Listeners', 22:21:30 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 22:21:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:30 INFO - 'USER': 'cltbld', 22:21:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:30 INFO - 'XPC_FLAGS': '0x0', 22:21:30 INFO - 'XPC_SERVICE_NAME': '0', 22:21:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:31 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 22:21:31 INFO - Cleaning up... 22:21:31 INFO - Return code: 0 22:21:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:31 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 0x10791ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079b0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4184c9170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10787fd30>, '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 0x1075e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107877b20>, '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.JRnS1oRoLy/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.rGxSuAmlwC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 22:21:31 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 22:21:31 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 22:21:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRnS1oRoLy/Render', 22:21:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:31 INFO - 'HOME': '/Users/cltbld', 22:21:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:31 INFO - 'LOGNAME': 'cltbld', 22:21:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:31 INFO - 'MOZ_NO_REMOTE': '1', 22:21:31 INFO - 'NO_EM_RESTART': '1', 22:21:31 INFO - 'PAGER': '/bin/cat', 22:21:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:31 INFO - 'PWD': '/builds/slave/test', 22:21:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:31 INFO - 'SHELL': '/bin/bash', 22:21:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rGxSuAmlwC/Listeners', 22:21:31 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 22:21:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:31 INFO - 'USER': 'cltbld', 22:21:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:31 INFO - 'XPC_FLAGS': '0x0', 22:21:31 INFO - 'XPC_SERVICE_NAME': '0', 22:21:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:31 INFO - Downloading/unpacking psutil>=3.1.1 22:21:31 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 22:21:31 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 22:21:31 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 22:21:31 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 22:21:31 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 22:21:31 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 22:21:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:21:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:21:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:21:34 INFO - Installing collected packages: psutil 22:21:34 INFO - Running setup.py install for psutil 22:21:34 INFO - building 'psutil._psutil_osx' extension 22:21:34 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 22:21:35 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 22:21:35 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 22:21:35 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 22:21:35 INFO - building 'psutil._psutil_posix' extension 22:21:35 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 22:21:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:21:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:21:35 INFO - ^ 22:21:35 INFO - 1 warning generated. 22:21:35 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 22:21:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:21:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:21:36 INFO - Successfully installed psutil 22:21:36 INFO - Cleaning up... 22:21:36 INFO - Return code: 0 22:21:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:36 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 0x10791ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079b0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4184c9170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10787fd30>, '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 0x1075e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107877b20>, '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.JRnS1oRoLy/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.rGxSuAmlwC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 22:21:36 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 22:21:36 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 22:21:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRnS1oRoLy/Render', 22:21:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:36 INFO - 'HOME': '/Users/cltbld', 22:21:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:36 INFO - 'LOGNAME': 'cltbld', 22:21:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:36 INFO - 'MOZ_NO_REMOTE': '1', 22:21:36 INFO - 'NO_EM_RESTART': '1', 22:21:36 INFO - 'PAGER': '/bin/cat', 22:21:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:36 INFO - 'PWD': '/builds/slave/test', 22:21:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:36 INFO - 'SHELL': '/bin/bash', 22:21:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rGxSuAmlwC/Listeners', 22:21:36 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 22:21:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:36 INFO - 'USER': 'cltbld', 22:21:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:36 INFO - 'XPC_FLAGS': '0x0', 22:21:36 INFO - 'XPC_SERVICE_NAME': '0', 22:21:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:21: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 22:21: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 22:21: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 22:21: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 22:21: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 22:21: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 22:21:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:21:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:21:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:21:38 INFO - Installing collected packages: mozsystemmonitor 22:21:38 INFO - Running setup.py install for mozsystemmonitor 22:21:39 INFO - Successfully installed mozsystemmonitor 22:21:39 INFO - Cleaning up... 22:21:39 INFO - Return code: 0 22:21:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:21: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10791ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079b0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4184c9170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10787fd30>, '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 0x1075e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107877b20>, '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.JRnS1oRoLy/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.rGxSuAmlwC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 22:21: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==2.5.1'] in /builds/slave/test/build 22:21: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==2.5.1 22:21:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRnS1oRoLy/Render', 22:21:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:39 INFO - 'HOME': '/Users/cltbld', 22:21:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:39 INFO - 'LOGNAME': 'cltbld', 22:21:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:39 INFO - 'MOZ_NO_REMOTE': '1', 22:21:39 INFO - 'NO_EM_RESTART': '1', 22:21:39 INFO - 'PAGER': '/bin/cat', 22:21:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:39 INFO - 'PWD': '/builds/slave/test', 22:21:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:39 INFO - 'SHELL': '/bin/bash', 22:21:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rGxSuAmlwC/Listeners', 22:21:39 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 22:21:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:39 INFO - 'USER': 'cltbld', 22:21:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:39 INFO - 'XPC_FLAGS': '0x0', 22:21:39 INFO - 'XPC_SERVICE_NAME': '0', 22:21:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:39 INFO - Downloading/unpacking jsonschema==2.5.1 22:21:39 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 22:21:39 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 22:21:39 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 22:21:39 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 22:21:39 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 22:21:39 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 22:21:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:21:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:21:41 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 22:21:41 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 22:21:41 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 22:21:41 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 22:21:41 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 22:21:41 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 22:21:42 INFO - Downloading functools32-3.2.3-2.tar.gz 22:21:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:21:42 INFO - warning: no files found matching '*.txt' 22:21:42 INFO - no previously-included directories found matching 'build' 22:21:42 INFO - no previously-included directories found matching 'dist' 22:21:42 INFO - no previously-included directories found matching '.git*' 22:21:42 INFO - Installing collected packages: jsonschema, functools32 22:21:42 INFO - Running setup.py install for functools32 22:21:42 INFO - warning: no files found matching '*.txt' 22:21:42 INFO - no previously-included directories found matching 'build' 22:21:42 INFO - no previously-included directories found matching 'dist' 22:21:42 INFO - no previously-included directories found matching '.git*' 22:21:42 INFO - Successfully installed jsonschema functools32 22:21:42 INFO - Cleaning up... 22:21:42 INFO - Return code: 0 22:21:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10791ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079b0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4184c9170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10787fd30>, '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 0x1075e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107877b20>, '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.JRnS1oRoLy/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.rGxSuAmlwC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 22:21:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:21:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:21:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRnS1oRoLy/Render', 22:21:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:42 INFO - 'HOME': '/Users/cltbld', 22:21:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:42 INFO - 'LOGNAME': 'cltbld', 22:21:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:42 INFO - 'MOZ_NO_REMOTE': '1', 22:21:42 INFO - 'NO_EM_RESTART': '1', 22:21:42 INFO - 'PAGER': '/bin/cat', 22:21:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:42 INFO - 'PWD': '/builds/slave/test', 22:21:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:42 INFO - 'SHELL': '/bin/bash', 22:21:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rGxSuAmlwC/Listeners', 22:21:42 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 22:21:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:42 INFO - 'USER': 'cltbld', 22:21:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:42 INFO - 'XPC_FLAGS': '0x0', 22:21:42 INFO - 'XPC_SERVICE_NAME': '0', 22:21:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:42 INFO - Downloading/unpacking blobuploader==1.2.4 22:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:45 INFO - Downloading blobuploader-1.2.4.tar.gz 22:21:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:21:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:21:45 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 22:21:45 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 22:21:45 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 22:21:45 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 22:21:45 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 22:21:45 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 22:21:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:21:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:21:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:21:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:21:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:21:46 INFO - Downloading docopt-0.6.1.tar.gz 22:21:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:21:46 INFO - Installing collected packages: blobuploader, requests, docopt 22:21:46 INFO - Running setup.py install for blobuploader 22:21:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:21:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:21:46 INFO - Running setup.py install for requests 22:21:47 INFO - Running setup.py install for docopt 22:21:47 INFO - Successfully installed blobuploader requests docopt 22:21:47 INFO - Cleaning up... 22:21:47 INFO - Return code: 0 22:21:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:47 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 0x10791ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079b0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4184c9170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10787fd30>, '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 0x1075e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107877b20>, '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.JRnS1oRoLy/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.rGxSuAmlwC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 22:21:47 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 22:21:47 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 22:21:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRnS1oRoLy/Render', 22:21:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:47 INFO - 'HOME': '/Users/cltbld', 22:21:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:47 INFO - 'LOGNAME': 'cltbld', 22:21:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:47 INFO - 'MOZ_NO_REMOTE': '1', 22:21:47 INFO - 'NO_EM_RESTART': '1', 22:21:47 INFO - 'PAGER': '/bin/cat', 22:21:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:47 INFO - 'PWD': '/builds/slave/test', 22:21:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:47 INFO - 'SHELL': '/bin/bash', 22:21:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rGxSuAmlwC/Listeners', 22:21:47 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 22:21:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:47 INFO - 'USER': 'cltbld', 22:21:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:47 INFO - 'XPC_FLAGS': '0x0', 22:21:47 INFO - 'XPC_SERVICE_NAME': '0', 22:21:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:21:47 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Yqh1w6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:21:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:21:47 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-pszeGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:21:48 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-nKW55O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:21:48 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-t7zb09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:21:48 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-GVZj6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:21:48 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-NOftw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:21:48 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-1Q5ylR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:21:48 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-0ct_Rk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:21:48 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-GLZ4ra-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:21:48 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-O9jaLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:21:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:21:48 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-m8E5Mj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:21:49 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-XuPjoG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:21:49 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-YXDQrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:21:49 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-Ai59Zu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:49 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-HTz74B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:21:49 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-jXOaJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:21:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:21:49 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-EpiYbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:21:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:21:49 INFO - Running setup.py install for manifestparser 22:21:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:21:49 INFO - Running setup.py install for mozcrash 22:21:49 INFO - Running setup.py install for mozdebug 22:21:50 INFO - Running setup.py install for mozdevice 22:21:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:21:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:21:50 INFO - Running setup.py install for mozfile 22:21:50 INFO - Running setup.py install for mozhttpd 22:21:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:21:50 INFO - Running setup.py install for mozinfo 22:21:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:21:50 INFO - Running setup.py install for mozInstall 22:21:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:21:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:21:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:21:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:21:50 INFO - Running setup.py install for mozleak 22:21:50 INFO - Running setup.py install for mozlog 22:21:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:21:51 INFO - Running setup.py install for moznetwork 22:21:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:21:51 INFO - Running setup.py install for mozprocess 22:21:51 INFO - Running setup.py install for mozprofile 22:21:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:21:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:21:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:21:51 INFO - Running setup.py install for mozrunner 22:21:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:21:51 INFO - Running setup.py install for mozscreenshot 22:21:51 INFO - Running setup.py install for moztest 22:21:52 INFO - Running setup.py install for mozversion 22:21:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:21:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:21:52 INFO - Cleaning up... 22:21:52 INFO - Return code: 0 22:21:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:52 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 0x10791ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079b0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4184c9170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10787fd30>, '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 0x1075e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107877b20>, '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.JRnS1oRoLy/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.rGxSuAmlwC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 22:21:52 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 22:21:52 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 22:21:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRnS1oRoLy/Render', 22:21:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:52 INFO - 'HOME': '/Users/cltbld', 22:21:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:52 INFO - 'LOGNAME': 'cltbld', 22:21:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:52 INFO - 'MOZ_NO_REMOTE': '1', 22:21:52 INFO - 'NO_EM_RESTART': '1', 22:21:52 INFO - 'PAGER': '/bin/cat', 22:21:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:52 INFO - 'PWD': '/builds/slave/test', 22:21:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:52 INFO - 'SHELL': '/bin/bash', 22:21:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rGxSuAmlwC/Listeners', 22:21:52 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 22:21:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:52 INFO - 'USER': 'cltbld', 22:21:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:52 INFO - 'XPC_FLAGS': '0x0', 22:21:52 INFO - 'XPC_SERVICE_NAME': '0', 22:21:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:21:52 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-uLebjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:21:52 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)) 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:21:52 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-L1rz6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:21:52 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)) 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:21:52 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-ffZU11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:21:52 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)) 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:21:52 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-MABUSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:21:52 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)) 22:21:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:21:52 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-lsztwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:21:53 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)) 22:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:21:53 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-vTiS7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:21:53 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)) 22:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:21:53 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-BlnKkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:21:53 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)) 22:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:21:53 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-dlCLRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:21:53 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)) 22:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:21:53 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-RZZKNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:21:53 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)) 22:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:21:53 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-f3UvSv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:21:53 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)) 22:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:21:53 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-gYvXZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:21:53 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)) 22:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:21:53 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-pf7FcL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:21:53 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)) 22:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:21:53 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-TW9v0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:21:53 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)) 22:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:21:53 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-zAsg69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:21:54 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)) 22:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:54 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-JoIIvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:21:54 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)) 22:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:21:54 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-ImXtNF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:21:54 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)) 22:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:21:54 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-sraF2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:21:54 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)) 22:21:54 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)) 22:21:54 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)) 22:21:54 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)) 22:21:54 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)) 22:21:54 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)) 22:21:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:21:54 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 22:21:54 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 22:21:54 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 22:21:54 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 22:21:54 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 22:21:54 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 22:21:56 INFO - Downloading blessings-1.6.tar.gz 22:21:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:21:56 INFO - Installing collected packages: blessings 22:21:56 INFO - Running setup.py install for blessings 22:21:57 INFO - Successfully installed blessings 22:21:57 INFO - Cleaning up... 22:21:57 INFO - Return code: 0 22:21:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:21:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:21:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:21:57 INFO - Reading from file tmpfile_stdout 22:21:57 INFO - Current package versions: 22:21:57 INFO - blessings == 1.6 22:21:57 INFO - blobuploader == 1.2.4 22:21:57 INFO - docopt == 0.6.1 22:21:57 INFO - functools32 == 3.2.3-2 22:21:57 INFO - jsonschema == 2.5.1 22:21:57 INFO - manifestparser == 1.1 22:21:57 INFO - mozInstall == 1.12 22:21:57 INFO - mozcrash == 0.17 22:21:57 INFO - mozdebug == 0.1 22:21:57 INFO - mozdevice == 0.48 22:21:57 INFO - mozfile == 1.2 22:21:57 INFO - mozhttpd == 0.7 22:21:57 INFO - mozinfo == 0.9 22:21:57 INFO - mozleak == 0.1 22:21:57 INFO - mozlog == 3.3 22:21:57 INFO - moznetwork == 0.27 22:21:57 INFO - mozprocess == 0.23 22:21:57 INFO - mozprofile == 0.28 22:21:57 INFO - mozrunner == 6.12 22:21:57 INFO - mozscreenshot == 0.1 22:21:57 INFO - mozsystemmonitor == 0.3 22:21:57 INFO - moztest == 0.7 22:21:57 INFO - mozversion == 1.4 22:21:57 INFO - psutil == 3.1.1 22:21:57 INFO - requests == 1.2.3 22:21:57 INFO - wsgiref == 0.1.2 22:21:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:21:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:57 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 0x10791ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079b0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4184c9170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10787fd30>, '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 0x1075e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107877b20>, '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.JRnS1oRoLy/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.rGxSuAmlwC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 22:21:57 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 22:21:57 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 22:21:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRnS1oRoLy/Render', 22:21:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:57 INFO - 'HOME': '/Users/cltbld', 22:21:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:57 INFO - 'LOGNAME': 'cltbld', 22:21:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:57 INFO - 'MOZ_NO_REMOTE': '1', 22:21:57 INFO - 'NO_EM_RESTART': '1', 22:21:57 INFO - 'PAGER': '/bin/cat', 22:21:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:57 INFO - 'PWD': '/builds/slave/test', 22:21:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:57 INFO - 'SHELL': '/bin/bash', 22:21:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rGxSuAmlwC/Listeners', 22:21:57 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 22:21:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:57 INFO - 'USER': 'cltbld', 22:21:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:57 INFO - 'XPC_FLAGS': '0x0', 22:21:57 INFO - 'XPC_SERVICE_NAME': '0', 22:21:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:57 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)) 22:21:57 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)) 22:21:57 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)) 22:21:57 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)) 22:21:57 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)) 22:21:57 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)) 22:21:57 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)) 22:21:57 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)) 22:21:57 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)) 22:21:57 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)) 22:21:57 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)) 22:21:57 INFO - Cleaning up... 22:21:57 INFO - Return code: 0 22:21:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:21:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:21:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:21:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:21:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:21:57 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 0x10791ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1079b0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4184c9170>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10787fd30>, '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 0x1075e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107877b20>, '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.JRnS1oRoLy/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.rGxSuAmlwC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 22:21:57 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 22:21:57 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 22:21:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRnS1oRoLy/Render', 22:21:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:21:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:21:57 INFO - 'HOME': '/Users/cltbld', 22:21:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:21:57 INFO - 'LOGNAME': 'cltbld', 22:21:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:21:57 INFO - 'MOZ_NO_REMOTE': '1', 22:21:57 INFO - 'NO_EM_RESTART': '1', 22:21:57 INFO - 'PAGER': '/bin/cat', 22:21:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:21:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:21:57 INFO - 'PWD': '/builds/slave/test', 22:21:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:21:57 INFO - 'SHELL': '/bin/bash', 22:21:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rGxSuAmlwC/Listeners', 22:21:57 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 22:21:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:21:57 INFO - 'USER': 'cltbld', 22:21:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:21:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:21:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:21:57 INFO - 'XPC_FLAGS': '0x0', 22:21:57 INFO - 'XPC_SERVICE_NAME': '0', 22:21:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:21:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:21:58 INFO - Cleaning up... 22:21:58 INFO - Return code: 0 22:21:58 INFO - Running post-action listener: _resource_record_post_action 22:21:58 INFO - Running post-action listener: _start_resource_monitoring 22:21:58 INFO - Starting resource monitoring. 22:21:58 INFO - [mozharness: 2016-07-22 05:21:58.096192Z] Finished create-virtualenv step (success) 22:21:58 INFO - [mozharness: 2016-07-22 05:21:58.097127Z] Running install step. 22:21:58 INFO - Running pre-action listener: _resource_record_pre_action 22:21:58 INFO - Running main action method: install 22:21:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:21:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:21:58 INFO - Reading from file tmpfile_stdout 22:21:58 INFO - Detecting whether we're running mozinstall >=1.0... 22:21:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:21:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:21:58 INFO - Reading from file tmpfile_stdout 22:21:58 INFO - Output received: 22:21:58 INFO - Usage: mozinstall [options] installer 22:21:58 INFO - Options: 22:21:58 INFO - -h, --help show this help message and exit 22:21:58 INFO - -d DEST, --destination=DEST 22:21:58 INFO - Directory to install application into. [default: 22:21:58 INFO - "/builds/slave/test"] 22:21:58 INFO - --app=APP Application being installed. [default: firefox] 22:21:58 INFO - mkdir: /builds/slave/test/build/application 22:21:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:21:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:22:33 INFO - Reading from file tmpfile_stdout 22:22:33 INFO - Output received: 22:22:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:22:33 INFO - Running post-action listener: _resource_record_post_action 22:22:33 INFO - [mozharness: 2016-07-22 05:22:33.342963Z] Finished install step (success) 22:22:33 INFO - [mozharness: 2016-07-22 05:22:33.343093Z] Running run-tests step. 22:22:33 INFO - Running pre-action listener: _resource_record_pre_action 22:22:33 INFO - Running main action method: run_tests 22:22:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:22:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:22:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:22:33 INFO - 2016-07-21 22:22:33.357 screenresolution[1645:8207] starting screenresolution argv=screenresolution get 22:22:33 INFO - 2016-07-21 22:22:33.360 screenresolution[1645:8207] Display 0: 1600x1200x32@60 22:22:33 INFO - 2016-07-21 22:22:33.365 screenresolution[1646:8209] starting screenresolution argv=screenresolution list 22:22:33 INFO - Available Modes on Display 0 22:22:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:22:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:22:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:22:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:22:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:22:33 INFO - Intel Iris: 22:22:33 INFO - Chipset Model: Intel Iris 22:22:33 INFO - Type: GPU 22:22:33 INFO - Bus: Built-In 22:22:33 INFO - VRAM (Dynamic, Max): 1536 MB 22:22:33 INFO - Vendor: Intel (0x8086) 22:22:33 INFO - Device ID: 0x0a2e 22:22:33 INFO - Revision ID: 0x0009 22:22:33 INFO - Displays: 22:22:33 INFO - Display: 22:22:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:22:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:22:33 INFO - Display Serial Number: 1600x1200 60 22:22:33 INFO - Main Display: Yes 22:22:33 INFO - Mirror: Off 22:22:33 INFO - Online: Yes 22:22:33 INFO - Rotation: Supported 22:22:33 INFO - Return code: 0 22:22:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:22:33 INFO - Python 2.7.3 22:22:33 INFO - Return code: 0 22:22:33 INFO - grabbing minidump binary from tooltool 22:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:22:33 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 0x10787fd30>, '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 0x1075e4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107877b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:22:33 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 22:22:33 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 22:22:33 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 22:22:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:22:33 INFO - Return code: 0 22:22:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:22:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:22:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:22:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:22:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:22:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0129', '--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 22:22:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0129 --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 22:22:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JRnS1oRoLy/Render', 22:22:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:22:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:22:33 INFO - 'HOME': '/Users/cltbld', 22:22:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:22:33 INFO - 'LOGNAME': 'cltbld', 22:22:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:22:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:22:33 INFO - 'MOZ_NO_REMOTE': '1', 22:22:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:22:33 INFO - 'NO_EM_RESTART': '1', 22:22:33 INFO - 'PAGER': '/bin/cat', 22:22:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:22:33 INFO - 'PWD': '/builds/slave/test', 22:22:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:22:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:22:33 INFO - 'SHELL': '/bin/bash', 22:22:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rGxSuAmlwC/Listeners', 22:22:33 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 22:22:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:22:33 INFO - 'USER': 'cltbld', 22:22:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:22:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:22:33 INFO - 'XPC_FLAGS': '0x0', 22:22:33 INFO - 'XPC_SERVICE_NAME': '0', 22:22:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:22:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0129', '--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 22:22:33 INFO - mozversion application_buildid: 20160721210813 22:22:33 INFO - mozversion application_changeset: 7f0562848c05433431f3c703edd151f24498a99f 22:22:33 INFO - mozversion application_display_name: Nightly 22:22:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:22:33 INFO - mozversion application_name: Firefox 22:22:33 INFO - mozversion application_remotingname: firefox 22:22:33 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 22:22:33 INFO - mozversion application_vendor: Mozilla 22:22:33 INFO - mozversion application_version: 50.0a1 22:22:33 INFO - mozversion platform_buildid: 20160721210813 22:22:33 INFO - mozversion platform_changeset: 7f0562848c05433431f3c703edd151f24498a99f 22:22:33 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 22:22:33 INFO - mozversion platform_version: 50.0a1 22:22:33 INFO - using testdate: 1469164953 22:22:33 INFO - actual date: 1469164953 22:22:33 INFO - starting webserver on 'localhost:49272' 22:22:33 INFO - SUITE-START | Running 2 tests 22:22:33 INFO - TEST-START | dromaeo_css 22:22:33 INFO - operating with platform_type : mac_ 22:22:33 INFO - Initialising browser for dromaeo_css test... 22:22:33 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpQjCQx4/profile http://localhost:49272/getInfo.html) 22:22:36 INFO - PROCESS | 1656 | JavaScript error: , line 0: uncaught exception: undefined 22:22:36 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 22:22:36 INFO - PROCESS | 1656 | colorDepth:24 22:22:36 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 22:22:36 INFO - PROCESS | 1656 | __metrics 22:22:36 INFO - PROCESS | 1656 | 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" 22:22:36 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:22:37 INFO - TEST-INFO | 1656: exit 0 22:22:37 INFO - Browser initialized. 22:22:37 INFO - Running cycle 1/1 for dromaeo_css test... 22:22:37 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpQjCQx4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:22:49 INFO - PROCESS | 1659 | RSS: Main: 245723136 22:22:49 INFO - PROCESS | 1659 | 22:23:41 INFO - PROCESS | 1659 | 1469165021030 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] 22:25:51 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 22:25:52 INFO - PROCESS | 1659 | RSS: Main: 350441472 22:25:52 INFO - PROCESS | 1659 | 22:28:56 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 22:28:56 INFO - PROCESS | 1659 | RSS: Main: 382386176 22:28:56 INFO - PROCESS | 1659 | 22:31:59 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 22:31:59 INFO - PROCESS | 1659 | RSS: Main: 385339392 22:31:59 INFO - PROCESS | 1659 | 22:35:02 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 22:35:02 INFO - PROCESS | 1659 | RSS: Main: 382648320 22:35:02 INFO - PROCESS | 1659 | 22:38:06 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 22:38:06 INFO - PROCESS | 1659 | RSS: Main: 352395264 22:38:06 INFO - PROCESS | 1659 | 22:41:10 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 22:41:10 INFO - PROCESS | 1659 | RSS: Main: 399650816 22:41:10 INFO - PROCESS | 1659 | 22:41:10 INFO - PROCESS | 1659 | __start_tp_report 22:41:10 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 22:41:10 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 22:41:10 INFO - PROCESS | 1659 | |i|pagename|runs| 22:41:10 INFO - PROCESS | 1659 | |0;dojo.html;2668.826526275792;3396.0491062502506;3399.1841957930087;3424.2980189061245;3460.5501284832985;5580;5652.491275785179;5691.601587888849;5771.307443106828;5792.855178620536;5686.914696279559;5736.053551164053;5747.913781293273;5750.856228594287;5754.712264386785;5166.819161329354;5210.7655155517905;5233.319668443097;5246.947530524703;5302.151655735083;4297.570242975708;5320.467953204688;5337.279467271907;5343.358796944372;5346.010987967219;5158.9742051289695;5159.845204643867;5178.818741344054;5185.611079169059;5317.2821669074565;5221.477852214767;5239.633225674204;5253.343332083489;5259.947400526023;5318.468153184689;5127.66670166438;5134.537891589737;5154.226865970097;5178.223266510016;5181.1969144782615;5637.774489020434;5672.858178545528;5731.570132240057;5735.139729040636;5745.511631511326;6316;6320.841978950563;6336.20797400325;6338.461230795388;6339.334369891141;5612.971935140339;6074.967255566565;6264.310925798159;6323.6521991290465;6334.049892516114;4921.335619691367;5847.415258474119;5925.051991681354;5930.377310382434;5938.079597662369;6329.936700632935;6330.39861213184;6332.620042797447;6336.809895703136;6340.619562826153;6031.336552979169;6095.66473843934;6102.176206212193;6201.224846894138;6234.844128896723;5976.581639285295;6255.624662520263;6270.467010304175;6298.1812364392335;6320.747170113144;6182.721777519932;6260.123582698426;6268.529860260499;6273.843153921097;6274.309825919146;6097.040762675633;6249.218847644044;6282.591631543935;6293.96853015732;6308.463780578702;4916.188828843201;4960.553550180501;4961.82633607822;4968.453470118284;4969.975150124226;4408.7575183365225;4474.7762611869275;4486.573775491323;4494.033782736662;4521.909561808746;5778.3643799182055;5789.710514474259;5805.767769289266;5850.678212698257;5855.180274761537;6260.280067792262;6284.968575157095;6330.556861019684;6344.587601805868;6367.395097465734;5647.774089036392;5702.600817942704;5704.629199102045;5790.478856902899;5831.300243970751;5453.754581043862;5455.5908306877145;5472.288602481652;5486.615936884459;5513.917291240635;5284.735763211824;6248;6297.716602752796;6305.4009869062365;6320.27316858558;6190.628562286277;6240.282367527792;6331.651759153291;6337.4613157882095;6344.143540622142;6301.495880329471;6364.809055728336;6396.680165991775;6398.520110991787;6404.327545607644;6246.750129994749;6246.968765156145;6258.43674069336;6277.466415354564;6283.71723272463;5963.731632076654;6187.566870319034;6303.716332765129;6312.368763123651;6323.462505687068;6136.140940268277;6136.815895523141;6169.81490555284;6202.317745048132;6203.2245969253845;6134.447899689049;6158;6175.320714721467;6178.011518157074;6197.442230199304;4963.503649635008;5294;5887.1757953885635;6007.098935159586;6029.547783916136;5171.767270472913;6089.238845144357;6147.293061297829;6159.5688301818445;6173.783917562864;5024.974875125601;5965.642061476326;5970.58205925581;5981.551383646331;6002.879942401215 22:41:10 INFO - PROCESS | 1659 | |1;ext.html;482.8865216674081;483.4609410507283;526.2211926349004;532.0263917031831;546.9179623056543;30488.695113048863;42422.36366454502;43271.99999999996;43383.78308108455;45378.31932521015;43631;44082.55917440825;44125.117497650026;44218.33672494907;44340.778296108474;9728.708138755814;9890.604375824958;9913.909469958298;9914.900850991506;9917.157041651468;8799.824003519925;9021.413608115485;11924.582639607615;12167.391630418488;12188.451519681636;12193.695157621087;12294.938525307361;12296.69258268546;12308.015358771321;12327.445264963098;8858.557072146366;12040.638780836545;12168.452419641137;12169.878301216962;12198.390080496029;11690.590829320932;12034.398280085961;12061.517539298418;12286;12315.815262771066;41294.38058429126;41758.58241417578;41786.79106604478;41788.58211417908;41793.58206417958;3629.2560025195035;3763.8682646107254;3763.9811800941093;3870.3420418528635;4086.2849001424634;27637.032703855468;27648.723512765016;27659.861700691366;27687.446251074867;27697.86151069252;3189.186757376854;3234.2884565395575;3431.3137372525343;3439.8968030959113;3458.9827050864583;462637;463973;465345;467009;467384;60250.09624855631;60694;60762.088568671505;60848.69575652182;60896.78206435935;63092.369076309566;63180;63193;63239.36760632427;63249.36750632527;13759.724805503834;16700.914440561326;16703.832961670472;16732.24704888283;16736.832631673526;11803.350815705218;11972.64082077539;12925.741485170243;13031.218126912227;13031.609051728463;15746;16108.75836862448;16210.59473513148;16284.267207975432;16547.503574892773;5776.740046697824;6387.297397286295;6804.591724496546;6805.863882722318;6944.409725173316;739.3567596191348;882.2853488674194;975.0541974284932;986.9703908882746;1107.6597317246221;29329.85335073311;31023.84488077545;31144.844275778476;31507.21231969173;32024;840.9466372086484;914.9588268527932;965.6577357473118;1143.9180691142208;1145.2269717940358;15157.848421515864;15564.766528502081;15610.375584976473;15829.050256984618;15972.281247343897;628413;630627;633388;633647;633997;57630.7118464405;57710.422895770505;57844.42155578474;57873.710631447415;57996.8400631985;1267.6577324122436;1397.7064816388672;1415.8938079644067;1430.5851303121979;1463.3488097796378;30971;31121.910733125136;31311.53032704557;31831.681683183797;32206;50271.49728502668;51016.23475647794;51051.74474127606;51262.48737512726;51296;470132;473763;477167;478847;478900;26194;27128.05051823177;27224.455510889275;27949.882004719584;27982;49025.754871225414;49132.75433622809;50150.74924625499;50222.2466662993;50274.24588631246;40314.798426008856;40889;41229.96925076897;41637.375439367825;41820.58179418167;39374.07824021997;41729.37405938974;44516.66450006505;45480;46068.30897536473;36748.63251367559;41558;42392.36411453893;44028.33957490576;44113.558864410945;42655.78672106619;43120;44268.778656106515;44997.77501112474;45378;31498.842505787325;31561.12360778012;33442.83278583591;33448.498272525445;34119.65880341165 22:41:10 INFO - PROCESS | 1659 | |2;jquery.html;361.2395906616572;361.4596178712825;382.1077783375819;389.65320864430663;411.6048593350383;15738.92130539347;15953;18820.62358752824;20543.767373957573;23098;13853.238071906042;20543.691844622343;21218.681719774217;21356;21412.25057122997;5325.627206095575;5577.330720313568;5582.246396736429;5585.385607583163;5590.18942253373;3947.7039222058315;4481.932771008437;5010.649254552164;5244.1347177715825;7099.219085900547;7048.400885924677;7061.929380706204;7066.787996360117;7084.893726594079;7086.787396378091;6854.348836860466;6939.653017349112;7015.508914375996;7034.19106802714;7043.154821421411;5808.099744539603;5917.970410147922;6184.196054512884;6197.16338294329;6199.56603037783;13788.172709637454;13830.861691383057;13899.16605003703;13965.882729381625;23094.884525577432;2168.5761047463157;2417.359399759065;2443;2481.0819996601194;2998.025641666467;10200.79598408035;10672.519736611872;11044.668659940215;11408;16556.006639601543;1898.8765730227492;1962.4210857796984;1964.666006778851;1986.7317912081828;2011.5675129847007;121548.56902861893;122255.77744222533;123946;125424;127798;10504.369737815756;11050.33697978124;11225.71935701614;11669.708257293465;11708.590199342982;11681.240719353385;13729.382177802023;14640.853591463949;14693.92653036728;14942.25288735559;8137.715179968673;8239.711610093618;8293.129221431784;8304.916950830415;8416.4950102994;6407.583507072025;6411.326810684905;8067.636956336979;8071.798205044921;8105.837883242302;4535.183666939983;4628.629709623222;4639.953600463952;4691;4711.693739906964;1657.651893102462;1665.6502134551638;1932.6327997680396;1981.6532106881239;1982.098145343865;841.7054031089192;893.446063440671;925.2597921662777;942.1473566422399;958.9520523973773;21627.567448650938;22051.448713782283;22051.448713782283;22080.889595552242;22291.331260062223;914.0904799724317;928.5682157796522;948.6964171465203;957.4590356448584;1004.1414590525142;5146.022255771391;5991.400859914061;6489.70796314167;11048.613298534512;11540.422978851191;451741;556529;572266;576337;580504;89701;135045;138002.999999998;146193;151930.24034879755;450.93236014597414;469.28199854223095;470.5130190253072;471.14016919122736;473.01612645696866;11297.830532542182;11651.59219427316;12008.699782505508;12047.457864395948;12065.819012714992;44469.77765111154;58174.709126454094;58248;58881.41118588931;59158.40841591529;67638.66180669262;85987;86629.56685216534;86651.56674216589;87159.56420217859;7611.2769286917655;7622.885656715264;7775.533467991939;7876.881846772418;8081.15147909461;10048.648297309852;11560.94219528897;11997.76004479888;12046.698832528906;12065.215760975509;8504.702335418231;9836.704898853046;10135.695929122137;10341.534630941474;10357.17142628603;9923.255755818487;9936;9945.751356216153;10297.794044119226;10434.582616695247;10012.84980725275;10248.795024099625;10267.973202679672;10301.072903438726;10593.470326483492;7619.69521219145;7842.29419352261;7876.8818467721885;7891.84216315659;8077.313428358655;7808;9090.59092340833;10339.224558158017;10418.635347762793;10444.112250458797 22:41:10 INFO - PROCESS | 1659 | |3;mootools.html;1393.3869097597062;1551.9922400388;1606.2450648195345;1785.8660600454969;1923.14420083063;7904;8623;8901.643934242622;8927.241184499317;9019.052999435064;8803.823923521542;8898.466092034483;8913.643454261823;8919.95540022299;8928.419652722569;2399.84401013934;2434.196715084018;2438.207582535674;2442.438239204984;2445.0953147335513;2386.95226095478;2424.9272521824373;2442.096424323003;2446.7430919753438;2448.069733501276;2387.8209134314907;2390.127603424755;2407.313915534073;2431.7689819467123;2437.878106094697;2367.9052837886466;2396.6405039244164;2405.987970060156;2412.674288970984;2415.7342692303832;2369.324742448394;2376.4415362389823;2399.076355603088;2400.8319417640773;2401;8910.465372077697;9008.009118996904;9025.819483610358;9025.864612030824;9027.277817774628;5291.391489978663;5487.807926722546;5496.203050557647;5532.834014979557;5542.168674698787;3706.962930370689;3725.590185079639;3775.528058992626;3780.073881898925;3782.0355809268735;938.5213541094023;940.6472572785206;1095.189559725805;1107.4850194659523;1117.0895719988264;11847.289162650268;11911.940440297829;12016.038716902714;12064.09519286057;12072.336021518813;4508.549145085465;4647.024124933734;4732.2428411454;4734.313524538922;4745.9050818984115;9485.810283794424;9486.098820612031;9506.287028472892;9506.762330941781;9524.619015239312;5372.167314066326;5381.219723140122;5381.623286369917;5393.919091213635;5394.3796463406425;4335.263005289108;4377.802998865059;4459.888502787391;4988;5031.949680503221;3846;3865.323568375523;3972.980135099364;3975.105601239698;3982.4623675803973;1020.6937918624383;1182.131133616791;1185.549491193341;1188.9227200231958;1195.7369378736664;817.8609636361833;879.520661239626;881.5239770523846;910.403685585929;956.6125719083782;5994.460498555151;6081.300650425169;6235.719392627343;6237.719302631393;6259.530535209878;950.4772375193754;974.6491263145263;1003.9598416063421;1006.6325791086201;1034.5701465392472;3321.534985102088;3863.092173339263;3937;4047.919041619151;4117.855875044359;10883.83674244887;11198.992090711876;11209.439528023566;11234;11242.718932026764;11043.282186658002;11164.218504704593;11181.608643697431;11186.832197517044;11190.776184476428;7885.842283154305;8229.423940324239;8354.749357519284;8495.74512764618;8495.872561911576;6063.151158837678;6200.534959878118;6253.7185826637;6271.78048768291;6324.462420694109;10023.69928902134;10249.538770755185;10278.794424111325;10280.897191027994;10296.021877921585;9365.578548965177;9593.28050396237;9672.564734587102;10037.397756134655;10038.447885366082;7639.197884222077;7820.139784623573;7898.486598371088;7901.762947111596;7915.564643944638;7318.487705860539;8674.609642565974;8766.780830479289;8772.824543508967;8787.209151176425;7724.8455030900195;7809.062912450542;7860.84278314442;7869.291763741291;8029.558374289237;7556.8866467001935;7631.3513351365755;7659.080910290801;7741.41939354562;7883.448158628841;7104.715811367488;7257.782266532013;7257.818554536178;7270;7274.96362518184;5046.728971962558;5159.426374955675;5245.475452454724;5248.186531087687;5267.525922666979;4241.257779888569;5107.438181799925;5112.054269960069;5316.946830531645;5378.408375078817 22:41:10 INFO - PROCESS | 1659 | |4;prototype.html;302.68369553816274;303.1678043769852;312.6685713144067;319.0492332848111;322.60964233277736;4634.5597168269005;5235;5276.868078298045;5330.546903513201;5417.945820541793;4145.191687620915;4377.890552736183;4986.4514903360605;5102.77037533311;5201.921971170435;4021.0349516116066;4027.375756757707;4035.737677050997;4047.959520404788;4070.6770299652585;3819.8090095495254;3830.0616348994536;4039.394090886376;4077.694172937027;4083.4278802661042;3697.3899306614376;3810.161764411825;4024.134811015631;4028.0131367814924;4040.2121586290687;3247.350529894031;3491.284286721216;3513.5608048993877;3735.2529494101154;4034.677225821927;3195.7762956593047;3261.037993791837;3525.189206482498;3561.7506774525796;3601.405768048269;4917.655764096515;5177.275181474596;5184.248283998799;5198.40218374888;5215.243789650517;4113.609207125318;4421.668374871865;4639.466461356953;4667.9766601166775;4690.413698287714;4839.249916262949;5531.225628412026;5539.723013849291;5546.084895992157;5546.696526316313;3272.9509057364157;3421.555197824292;3923.8430462781453;3924.3917192835156;3958.604139586047;5681.7500149966945;5868.941310586925;5871.884341975011;5917.319508256563;5918.348981612019;3278.6885245901603;3888.4361767544115;3985.5416627087675;3990.6009399059863;4018.7588744675413;4038.7778672173313;4039.939400908989;4046.9275641954905;4049.3318602430268;4063.309237429644;4043.130726893673;4254.383114448449;4490.39379683745;4611.584957353854;4647.767611619405;5109;5159.974200128975;5584.97207513968;5615.410381909923;5637.577181711388;1228.7235372041218;1300.6033159886135;1343.8320209973754;1384.882285005786;1387.1399732166121;811.7321283976273;874.8993865705398;877.828099487179;882.7572417585216;902.7066203483894;4091.590840915944;4843.602416803635;5021.748912554358;5036.874078148002;5126.794928202905;4940.950590494121;5668.914966275509;5676.744546495418;5702;5704.030314846486;4323.535584716744;4893.69675469057;5113.3097031900215;5114.5652619527755;5124.718140502308;2526.50733107046;2834.7243740316526;2903.666078401011;2931.736143747073;3008.6611457901463;5024.563086902849;5157.948420515747;5705.74324155414;5725.312962444534;5801.245838041028;5454.12733962572;5487.368952570426;5791.768329266866;5801.767929282866;5943.524518038546;4688.5619477872;4766.879783251003;4790.610722890392;6054.424829641177;6064.787732429256;4858.562729354375;5428.70142142186;5774.249347584787;5807.709614519342;5845.649261044351;4924.359833221658;5445.782168713207;5718.51392631631;5732.455416735404;5762.049261871744;5414.377346605191;6005.849853753691;6006.759729610942;6028.487578555696;6035.943709850846;3397.711194548392;3578.141246100969;3892.4939757831303;3903.10228647407;3907.5701672816535;4927.482614325444;5476.576090216652;5761.596688231783;5778.884422311614;5791.220214297076;4392.780360982002;4995.325631039908;5079.339685840817;5203.895922081613;5472.890542189213;4578.679492435631;4579.427571553556;5315.654482458628;5442.23808666779;5544.006236632174;5381.811636592701;5440.374356949002;5441.865782554025;5459.508644222039;5513.338399392099;5299.973500132474;5582.520632032433;5595.608307418442;5631.662100273997;5631.802886898939;4993.925091123709;5321.760520776497;5482.643628164316;5503.752331145028;5526.585506086979 22:41:10 INFO - PROCESS | 1659 | |5;yui.html;760.825010247643;765.4029856711765;785.089296416157;789.668339297495;833.2468456714159;15199.316030778615;16649.750253746188;16665.00009999398;16672.499825005238;16727.080010599428;15017.62455938602;15202.847971520281;15203.695926081471;15205.771913421308;15247.61880952974;694.3230350408362;715.4419552748839;876.4872549558515;879.199928065459;904.6607522179182;890.6437425030008;891.4294851295176;904.9049849765746;905.4386280506095;907.5598334807613;961.2598299309527;963.0995019656643;965.1844191658038;966.1015255812092;967.762898089969;738.4646131554633;920.3833431600843;955.4362925873768;956.4261443134098;956.7129861041694;707.745211723779;708.7023450150949;710.9146902371748;711.5588335232189;711.8398360368927;15446.768298475532;15470.071795692294;15483.303251353604;15500.069995800175;15520.5343839685;14121.646958826112;14255.287235638176;14366.098031341866;14384.64038399038;14421.567352979428;13284.667883302898;13305.334733263298;13360;13411.46354145833;13725.931370343083;3771.9434208486896;4630.9073818523775;4665.6748904220385;4709.670323077386;4830.082284365994;120386.59226815414;121441.39279303723;121456;121555;121748;9780.55987480565;11220.382878941737;11415.543378264943;11522.135839812048;11857.34784586856;21444.785552144593;22429.439264018205;22601.4349641257;22619;22653.43366415853;7899.131095579482;8060.717874874351;8148.247323505037;8154.469959452616;8276.462029968034;4362.083962367875;4476.7090139140855;4520.751358675305;4547.931781023287;4634.2585186370015;3521.207728261121;3663.8900832975055;3665.5051568038507;3667.743257971916;3668.1196512836727;645.8030300758314;654.254150268694;659.6635715784985;670.815525730428;680.3162821364497;567.2880534928721;598.8053882488209;605.8061420345447;654.7413771560207;729.6205972894065;9026.503542305105;9682.75793105178;9823.607055717692;9846.458444785463;9867.851982220272;565.9971700141556;575.3670961941914;623.1182876230083;674.4840197249108;689.7103216649019;4529.773511324486;4818.521908703777;4819.2530157825595;4831.73425461596;4855.902881942341;98967.50516247516;99285.5107173393;141508;147145.26427368008;152232;81529;83141.58429207816;118199;127826;128737;104925.47537262418;111481;114677;114745.4262728681;116114.41942790232;8886.422382545114;9171.35800493959;9189.310801689766;9194.678186263449;9196.402233854778;44095.33856992212;49317.75341123271;54773.45226547843;55941;58143.12785308308;62567;83425.58287208524;86277.1372286269;88534.11465885259;90028.09971900197;10750.569977200825;12306.507739690313;12741.49034038628;12768.74462510763;12943.676408089874;13195.670108246986;13386.732265354833;13521.72956540844;13572.660683482993;13726.176429414267;12589;12599.118061735591;12652.17760845542;12667.556635517712;12872.034597405045;12542.874571254171;12608.621741347773;12712.618621441372;12726.490940362652;12888.420021098886;12486.562970295996;12493.875061249635;12524.874751252371;12935.935320323339;12944.8058279124;8869.73390798277;10235.48822558884;10668.946655266674;10746.570137194425;10748.785024299626;10650.520726567169;10764.3541387517;10859.674209773719;10891.638545181851;10897.564097436014 22:41:10 INFO - PROCESS | 1659 | __end_tp_report 22:41:10 INFO - PROCESS | 1659 | __start_cc_report 22:41:10 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1303 22:41:10 INFO - PROCESS | 1659 | __end_cc_report 22:41:10 INFO - PROCESS | 1659 | __startTimestamp1469166070409__endTimestamp 22:41:10 INFO - PROCESS | 1659 | 22:41:10 INFO - PROCESS | 1659 | ------- Summary: start ------- 22:41:10 INFO - PROCESS | 1659 | Number of tests: 6 22:41:10 INFO - PROCESS | 1659 | 22:41:10 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5741.22 Median:5963.73 stddev:675.07 (11.3%) stddev-sans-first:632.66 22:41:10 INFO - PROCESS | 1659 | Values: 2668.8 3396.0 3399.2 3424.3 3460.6 5580.0 5652.5 5691.6 5771.3 5792.9 5686.9 5736.1 5747.9 5750.9 5754.7 5166.8 5210.8 5233.3 5246.9 5302.2 4297.6 5320.5 5337.3 5343.4 5346.0 5159.0 5159.8 5178.8 5185.6 5317.3 5221.5 5239.6 5253.3 5259.9 5318.5 5127.7 5134.5 5154.2 5178.2 5181.2 5637.8 5672.9 5731.6 5735.1 5745.5 6316.0 6320.8 6336.2 6338.5 6339.3 5613.0 6075.0 6264.3 6323.7 6334.0 4921.3 5847.4 5925.1 5930.4 5938.1 6329.9 6330.4 6332.6 6336.8 6340.6 6031.3 6095.7 6102.2 6201.2 6234.8 5976.6 6255.6 6270.5 6298.2 6320.7 6182.7 6260.1 6268.5 6273.8 6274.3 6097.0 6249.2 6282.6 6294.0 6308.5 4916.2 4960.6 4961.8 4968.5 4970.0 4408.8 4474.8 4486.6 4494.0 4521.9 5778.4 5789.7 5805.8 5850.7 5855.2 6260.3 6285.0 6330.6 6344.6 6367.4 5647.8 5702.6 5704.6 5790.5 5831.3 5453.8 5455.6 5472.3 5486.6 5513.9 5284.7 6248.0 6297.7 6305.4 6320.3 6190.6 6240.3 6331.7 6337.5 6344.1 6301.5 6364.8 6396.7 6398.5 6404.3 6246.8 6247.0 6258.4 6277.5 6283.7 5963.7 6187.6 6303.7 6312.4 6323.5 6136.1 6136.8 6169.8 6202.3 6203.2 6134.4 6158.0 6175.3 6178.0 6197.4 4963.5 5294.0 5887.2 6007.1 6029.5 5171.8 6089.2 6147.3 6159.6 6173.8 5025.0 5965.6 5970.6 5981.6 6002.9 22:41:10 INFO - PROCESS | 1659 | 22:41:10 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:67710.05 Median:41772.69 stddev:141045.23 (337.6%) stddev-sans-first:141350.58 22:41:10 INFO - PROCESS | 1659 | Values: 482.9 483.5 526.2 532.0 546.9 30488.7 42422.4 43272.0 43383.8 45378.3 43631.0 44082.6 44125.1 44218.3 44340.8 9728.7 9890.6 9913.9 9914.9 9917.2 8799.8 9021.4 11924.6 12167.4 12188.5 12193.7 12294.9 12296.7 12308.0 12327.4 8858.6 12040.6 12168.5 12169.9 12198.4 11690.6 12034.4 12061.5 12286.0 12315.8 41294.4 41758.6 41786.8 41788.6 41793.6 3629.3 3763.9 3764.0 3870.3 4086.3 27637.0 27648.7 27659.9 27687.4 27697.9 3189.2 3234.3 3431.3 3439.9 3459.0 462637.0 463973.0 465345.0 467009.0 467384.0 60250.1 60694.0 60762.1 60848.7 60896.8 63092.4 63180.0 63193.0 63239.4 63249.4 13759.7 16700.9 16703.8 16732.2 16736.8 11803.4 11972.6 12925.7 13031.2 13031.6 15746.0 16108.8 16210.6 16284.3 16547.5 5776.7 6387.3 6804.6 6805.9 6944.4 739.4 882.3 975.1 987.0 1107.7 29329.9 31023.8 31144.8 31507.2 32024.0 840.9 915.0 965.7 1143.9 1145.2 15157.8 15564.8 15610.4 15829.1 15972.3 628413.0 630627.0 633388.0 633647.0 633997.0 57630.7 57710.4 57844.4 57873.7 57996.8 1267.7 1397.7 1415.9 1430.6 1463.3 30971.0 31121.9 31311.5 31831.7 32206.0 50271.5 51016.2 51051.7 51262.5 51296.0 470132.0 473763.0 477167.0 478847.0 478900.0 26194.0 27128.1 27224.5 27949.9 27982.0 49025.8 49132.8 50150.7 50222.2 50274.2 40314.8 40889.0 41230.0 41637.4 41820.6 39374.1 41729.4 44516.7 45480.0 46068.3 36748.6 41558.0 42392.4 44028.3 44113.6 42655.8 43120.0 44268.8 44997.8 45378.0 31498.8 31561.1 33442.8 33448.5 34119.7 22:41:10 INFO - PROCESS | 1659 | 22:41:10 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:33413.59 Median:22440.69 stddev:92834.98 (413.7%) stddev-sans-first:93062.23 22:41:10 INFO - PROCESS | 1659 | Values: 361.2 361.5 382.1 389.7 411.6 15738.9 15953.0 18820.6 20543.8 23098.0 13853.2 20543.7 21218.7 21356.0 21412.3 5325.6 5577.3 5582.2 5585.4 5590.2 3947.7 4481.9 5010.6 5244.1 7099.2 7048.4 7061.9 7066.8 7084.9 7086.8 6854.3 6939.7 7015.5 7034.2 7043.2 5808.1 5918.0 6184.2 6197.2 6199.6 13788.2 13830.9 13899.2 13965.9 23094.9 2168.6 2417.4 2443.0 2481.1 2998.0 10200.8 10672.5 11044.7 11408.0 16556.0 1898.9 1962.4 1964.7 1986.7 2011.6 121548.6 122255.8 123946.0 125424.0 127798.0 10504.4 11050.3 11225.7 11669.7 11708.6 11681.2 13729.4 14640.9 14693.9 14942.3 8137.7 8239.7 8293.1 8304.9 8416.5 6407.6 6411.3 8067.6 8071.8 8105.8 4535.2 4628.6 4640.0 4691.0 4711.7 1657.7 1665.7 1932.6 1981.7 1982.1 841.7 893.4 925.3 942.1 959.0 21627.6 22051.4 22051.4 22080.9 22291.3 914.1 928.6 948.7 957.5 1004.1 5146.0 5991.4 6489.7 11048.6 11540.4 451741.0 556529.0 572266.0 576337.0 580504.0 89701.0 135045.0 138003.0 146193.0 151930.2 450.9 469.3 470.5 471.1 473.0 11297.8 11651.6 12008.7 12047.5 12065.8 44469.8 58174.7 58248.0 58881.4 59158.4 67638.7 85987.0 86629.6 86651.6 87159.6 7611.3 7622.9 7775.5 7876.9 8081.2 10048.6 11560.9 11997.8 12046.7 12065.2 8504.7 9836.7 10135.7 10341.5 10357.2 9923.3 9936.0 9945.8 10297.8 10434.6 10012.8 10248.8 10268.0 10301.1 10593.5 7619.7 7842.3 7876.9 7891.8 8077.3 7808.0 9090.6 10339.2 10418.6 10444.1 22:41:10 INFO - PROCESS | 1659 | 22:41:10 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5828.09 Median:5109.75 stddev:3268.88 (64.0%) stddev-sans-first:3261.06 22:41:10 INFO - PROCESS | 1659 | Values: 1393.4 1552.0 1606.2 1785.9 1923.1 7904.0 8623.0 8901.6 8927.2 9019.1 8803.8 8898.5 8913.6 8920.0 8928.4 2399.8 2434.2 2438.2 2442.4 2445.1 2387.0 2424.9 2442.1 2446.7 2448.1 2387.8 2390.1 2407.3 2431.8 2437.9 2367.9 2396.6 2406.0 2412.7 2415.7 2369.3 2376.4 2399.1 2400.8 2401.0 8910.5 9008.0 9025.8 9025.9 9027.3 5291.4 5487.8 5496.2 5532.8 5542.2 3707.0 3725.6 3775.5 3780.1 3782.0 938.5 940.6 1095.2 1107.5 1117.1 11847.3 11911.9 12016.0 12064.1 12072.3 4508.5 4647.0 4732.2 4734.3 4745.9 9485.8 9486.1 9506.3 9506.8 9524.6 5372.2 5381.2 5381.6 5393.9 5394.4 4335.3 4377.8 4459.9 4988.0 5031.9 3846.0 3865.3 3973.0 3975.1 3982.5 1020.7 1182.1 1185.5 1188.9 1195.7 817.9 879.5 881.5 910.4 956.6 5994.5 6081.3 6235.7 6237.7 6259.5 950.5 974.6 1004.0 1006.6 1034.6 3321.5 3863.1 3937.0 4047.9 4117.9 10883.8 11199.0 11209.4 11234.0 11242.7 11043.3 11164.2 11181.6 11186.8 11190.8 7885.8 8229.4 8354.7 8495.7 8495.9 6063.2 6200.5 6253.7 6271.8 6324.5 10023.7 10249.5 10278.8 10280.9 10296.0 9365.6 9593.3 9672.6 10037.4 10038.4 7639.2 7820.1 7898.5 7901.8 7915.6 7318.5 8674.6 8766.8 8772.8 8787.2 7724.8 7809.1 7860.8 7869.3 8029.6 7556.9 7631.4 7659.1 7741.4 7883.4 7104.7 7257.8 7257.8 7270.0 7275.0 5046.7 5159.4 5245.5 5248.2 5267.5 4241.3 5107.4 5112.1 5316.9 5378.4 22:41:10 INFO - PROCESS | 1659 | 22:41:10 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4472.72 Median:4994.63 stddev:1378.38 (27.6%) stddev-sans-first:1346.24 22:41:10 INFO - PROCESS | 1659 | Values: 302.7 303.2 312.7 319.0 322.6 4634.6 5235.0 5276.9 5330.5 5417.9 4145.2 4377.9 4986.5 5102.8 5201.9 4021.0 4027.4 4035.7 4048.0 4070.7 3819.8 3830.1 4039.4 4077.7 4083.4 3697.4 3810.2 4024.1 4028.0 4040.2 3247.4 3491.3 3513.6 3735.3 4034.7 3195.8 3261.0 3525.2 3561.8 3601.4 4917.7 5177.3 5184.2 5198.4 5215.2 4113.6 4421.7 4639.5 4668.0 4690.4 4839.2 5531.2 5539.7 5546.1 5546.7 3273.0 3421.6 3923.8 3924.4 3958.6 5681.8 5868.9 5871.9 5917.3 5918.3 3278.7 3888.4 3985.5 3990.6 4018.8 4038.8 4039.9 4046.9 4049.3 4063.3 4043.1 4254.4 4490.4 4611.6 4647.8 5109.0 5160.0 5585.0 5615.4 5637.6 1228.7 1300.6 1343.8 1384.9 1387.1 811.7 874.9 877.8 882.8 902.7 4091.6 4843.6 5021.7 5036.9 5126.8 4941.0 5668.9 5676.7 5702.0 5704.0 4323.5 4893.7 5113.3 5114.6 5124.7 2526.5 2834.7 2903.7 2931.7 3008.7 5024.6 5157.9 5705.7 5725.3 5801.2 5454.1 5487.4 5791.8 5801.8 5943.5 4688.6 4766.9 4790.6 6054.4 6064.8 4858.6 5428.7 5774.2 5807.7 5845.6 4924.4 5445.8 5718.5 5732.5 5762.0 5414.4 6005.8 6006.8 6028.5 6035.9 3397.7 3578.1 3892.5 3903.1 3907.6 4927.5 5476.6 5761.6 5778.9 5791.2 4392.8 4995.3 5079.3 5203.9 5472.9 4578.7 4579.4 5315.7 5442.2 5544.0 5381.8 5440.4 5441.9 5459.5 5513.3 5300.0 5582.5 5595.6 5631.7 5631.8 4993.9 5321.8 5482.6 5503.8 5526.6 22:41:10 INFO - PROCESS | 1659 | 22:41:10 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:23570.59 Median:24228.71 stddev:37032.41 (152.8%) stddev-sans-first:37096.69 22:41:10 INFO - PROCESS | 1659 | Values: 760.8 765.4 785.1 789.7 833.2 15199.3 16649.8 16665.0 16672.5 16727.1 15017.6 15202.8 15203.7 15205.8 15247.6 694.3 715.4 876.5 879.2 904.7 890.6 891.4 904.9 905.4 907.6 961.3 963.1 965.2 966.1 967.8 738.5 920.4 955.4 956.4 956.7 707.7 708.7 710.9 711.6 711.8 15446.8 15470.1 15483.3 15500.1 15520.5 14121.6 14255.3 14366.1 14384.6 14421.6 13284.7 13305.3 13360.0 13411.5 13725.9 3771.9 4630.9 4665.7 4709.7 4830.1 120386.6 121441.4 121456.0 121555.0 121748.0 9780.6 11220.4 11415.5 11522.1 11857.3 21444.8 22429.4 22601.4 22619.0 22653.4 7899.1 8060.7 8148.2 8154.5 8276.5 4362.1 4476.7 4520.8 4547.9 4634.3 3521.2 3663.9 3665.5 3667.7 3668.1 645.8 654.3 659.7 670.8 680.3 567.3 598.8 605.8 654.7 729.6 9026.5 9682.8 9823.6 9846.5 9867.9 566.0 575.4 623.1 674.5 689.7 4529.8 4818.5 4819.3 4831.7 4855.9 98967.5 99285.5 141508.0 147145.3 152232.0 81529.0 83141.6 118199.0 127826.0 128737.0 104925.5 111481.0 114677.0 114745.4 116114.4 8886.4 9171.4 9189.3 9194.7 9196.4 44095.3 49317.8 54773.5 55941.0 58143.1 62567.0 83425.6 86277.1 88534.1 90028.1 10750.6 12306.5 12741.5 12768.7 12943.7 13195.7 13386.7 13521.7 13572.7 13726.2 12589.0 12599.1 12652.2 12667.6 12872.0 12542.9 12608.6 12712.6 12726.5 12888.4 12486.6 12493.9 12524.9 12935.9 12944.8 8869.7 10235.5 10668.9 10746.6 10748.8 10650.5 10764.4 10859.7 10891.6 10897.6 22:41:10 INFO - PROCESS | 1659 | -------- Summary: end -------- 22:41:10 INFO - PROCESS | 1659 | 22:41:11 INFO - TEST-INFO | 1659: exit 0 22:41:11 INFO - TEST-OK | dromaeo_css | took 1117072ms 22:41:11 INFO - TEST-START | kraken 22:41:11 INFO - operating with platform_type : mac_ 22:41:11 INFO - Initialising browser for kraken test... 22:41:11 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmp1dxoaf/profile http://localhost:49272/getInfo.html) 22:41:13 INFO - PROCESS | 1665 | JavaScript error: , line 0: uncaught exception: undefined 22:41:13 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 22:41:13 INFO - PROCESS | 1665 | colorDepth:24 22:41:13 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 22:41:13 INFO - PROCESS | 1665 | __metrics 22:41:13 INFO - PROCESS | 1665 | 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" 22:41:13 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:41:14 INFO - TEST-INFO | 1665: exit 0 22:41:14 INFO - Browser initialized. 22:41:14 INFO - Running cycle 1/1 for kraken test... 22:41:14 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmp1dxoaf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:41:26 INFO - PROCESS | 1666 | RSS: Main: 245530624 22:41:26 INFO - PROCESS | 1666 | 22:41:28 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 22:41:29 INFO - PROCESS | 1666 | RSS: Main: 324161536 22:41:29 INFO - PROCESS | 1666 | 22:41:32 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 22:41:33 INFO - PROCESS | 1666 | RSS: Main: 335314944 22:41:33 INFO - PROCESS | 1666 | 22:41:36 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 22:41:37 INFO - PROCESS | 1666 | RSS: Main: 425693184 22:41:37 INFO - PROCESS | 1666 | 22:41:39 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 22:41:39 INFO - PROCESS | 1666 | RSS: Main: 328757248 22:41:39 INFO - PROCESS | 1666 | 22:41:41 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:41:42 INFO - PROCESS | 1666 | RSS: Main: 334471168 22:41:42 INFO - PROCESS | 1666 | 22:41:45 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 22:41:46 INFO - PROCESS | 1666 | RSS: Main: 372121600 22:41:46 INFO - PROCESS | 1666 | 22:41:49 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 22:41:49 INFO - PROCESS | 1666 | RSS: Main: 371441664 22:41:49 INFO - PROCESS | 1666 | 22:41:52 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 22:41:53 INFO - PROCESS | 1666 | RSS: Main: 373305344 22:41:53 INFO - PROCESS | 1666 | 22:41:54 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:41:55 INFO - PROCESS | 1666 | RSS: Main: 350703616 22:41:55 INFO - PROCESS | 1666 | 22:41:57 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:41:57 INFO - PROCESS | 1666 | RSS: Main: 356995072 22:41:57 INFO - PROCESS | 1666 | 22:42:00 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:42:00 INFO - PROCESS | 1666 | RSS: Main: 354922496 22:42:00 INFO - PROCESS | 1666 | 22:42:03 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:42:04 INFO - PROCESS | 1666 | RSS: Main: 363810816 22:42:04 INFO - PROCESS | 1666 | 22:42:07 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:42:07 INFO - PROCESS | 1666 | RSS: Main: 366174208 22:42:07 INFO - PROCESS | 1666 | 22:42:09 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 22:42:10 INFO - PROCESS | 1666 | RSS: Main: 409944064 22:42:10 INFO - PROCESS | 1666 | 22:42:10 INFO - PROCESS | 1666 | __start_tp_report 22:42:10 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 22:42:10 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 22:42:10 INFO - PROCESS | 1666 | |i|pagename|runs| 22:42:10 INFO - PROCESS | 1666 | |0;ai-astar;101;113;112;112;113;111;113;110;113;112 22:42:10 INFO - PROCESS | 1666 | |1;audio-beat-detection;142;117;108;162;119;106;129;118;142;109 22:42:10 INFO - PROCESS | 1666 | |2;audio-dft;186;181;184;182;183;174;181;187;181;183 22:42:10 INFO - PROCESS | 1666 | |3;audio-fft;78;57;58;107;59;57;66;68;85;56 22:42:10 INFO - PROCESS | 1666 | |4;audio-oscillator;82;70;122;101;116;68;96;69;69;102 22:42:10 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;88;86;88;88;89;90;134;111;89;85 22:42:10 INFO - PROCESS | 1666 | |6;imaging-darkroom;94;96;96;95;95;95;95;95;95;95 22:42:10 INFO - PROCESS | 1666 | |7;imaging-desaturate;84;84;84;86;84;83;83;84;85;83 22:42:10 INFO - PROCESS | 1666 | |8;json-parse-financial;54;52;52;53;53;53;53;53;54;52 22:42:10 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;67;74;71;76;72;70;71;72;71;72 22:42:10 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;83;89;100;101;77;84;81;90;100;102 22:42:10 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;140;147;151;136;136;134;136;134;220;136 22:42:10 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;163;163;160;162;161;163;162;162;163;160 22:42:10 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;69;68;82;84;84;82;65;65;66;66 22:42:10 INFO - PROCESS | 1666 | __end_tp_report 22:42:10 INFO - PROCESS | 1666 | __start_cc_report 22:42:10 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,2139 22:42:10 INFO - PROCESS | 1666 | __end_cc_report 22:42:10 INFO - PROCESS | 1666 | __startTimestamp1469166130024__endTimestamp 22:42:10 INFO - PROCESS | 1666 | 22:42:10 INFO - PROCESS | 1666 | ------- Summary: start ------- 22:42:10 INFO - PROCESS | 1666 | Number of tests: 14 22:42:10 INFO - PROCESS | 1666 | 22:42:10 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:111.00 Median:112.50 stddev:3.65 (3.2%) stddev-sans-first:1.05 22:42:10 INFO - PROCESS | 1666 | Values: 101.0 113.0 112.0 112.0 113.0 111.0 113.0 110.0 113.0 112.0 22:42:10 INFO - PROCESS | 1666 | 22:42:10 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:125.20 Median:124.00 stddev:18.31 (14.8%) stddev-sans-first:18.38 22:42:10 INFO - PROCESS | 1666 | Values: 142.0 117.0 108.0 162.0 119.0 106.0 129.0 118.0 142.0 109.0 22:42:10 INFO - PROCESS | 1666 | 22:42:10 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:182.20 Median:183.00 stddev:3.55 (1.9%) stddev-sans-first:3.49 22:42:10 INFO - PROCESS | 1666 | Values: 186.0 181.0 184.0 182.0 183.0 174.0 181.0 187.0 181.0 183.0 22:42:10 INFO - PROCESS | 1666 | 22:42:10 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:69.10 Median:62.50 stddev:16.56 (26.5%) stddev-sans-first:17.25 22:42:10 INFO - PROCESS | 1666 | Values: 78.0 57.0 58.0 107.0 59.0 57.0 66.0 68.0 85.0 56.0 22:42:10 INFO - PROCESS | 1666 | 22:42:10 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:89.50 Median:69.00 stddev:20.62 (29.9%) stddev-sans-first:21.70 22:42:10 INFO - PROCESS | 1666 | Values: 82.0 70.0 122.0 101.0 116.0 68.0 96.0 69.0 69.0 102.0 22:42:10 INFO - PROCESS | 1666 | 22:42:10 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:94.80 Median:88.00 stddev:15.64 (17.8%) stddev-sans-first:16.39 22:42:10 INFO - PROCESS | 1666 | Values: 88.0 86.0 88.0 88.0 89.0 90.0 134.0 111.0 89.0 85.0 22:42:10 INFO - PROCESS | 1666 | 22:42:10 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.44 22:42:10 INFO - PROCESS | 1666 | Values: 94.0 96.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 22:42:10 INFO - PROCESS | 1666 | 22:42:10 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:84.00 Median:84.00 stddev:0.94 (1.1%) stddev-sans-first:1.00 22:42:10 INFO - PROCESS | 1666 | Values: 84.0 84.0 84.0 86.0 84.0 83.0 83.0 84.0 85.0 83.0 22:42:10 INFO - PROCESS | 1666 | 22:42:10 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:53.00 stddev:0.74 (1.4%) stddev-sans-first:0.67 22:42:10 INFO - PROCESS | 1666 | Values: 54.0 52.0 52.0 53.0 53.0 53.0 53.0 53.0 54.0 52.0 22:42:10 INFO - PROCESS | 1666 | 22:42:10 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:72.00 stddev:2.37 (3.3%) stddev-sans-first:1.83 22:42:10 INFO - PROCESS | 1666 | Values: 67.0 74.0 71.0 76.0 72.0 70.0 71.0 72.0 71.0 72.0 22:42:10 INFO - PROCESS | 1666 | 22:42:10 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:90.70 Median:82.00 stddev:9.41 (11.5%) stddev-sans-first:9.55 22:42:10 INFO - PROCESS | 1666 | Values: 83.0 89.0 100.0 101.0 77.0 84.0 81.0 90.0 100.0 102.0 22:42:10 INFO - PROCESS | 1666 | 22:42:10 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:138.00 stddev:26.28 (19.0%) stddev-sans-first:27.75 22:42:10 INFO - PROCESS | 1666 | Values: 140.0 147.0 151.0 136.0 136.0 134.0 136.0 134.0 220.0 136.0 22:42:10 INFO - PROCESS | 1666 | 22:42:10 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.90 Median:162.50 stddev:1.20 (0.7%) stddev-sans-first:1.20 22:42:10 INFO - PROCESS | 1666 | Values: 163.0 163.0 160.0 162.0 161.0 163.0 162.0 162.0 163.0 160.0 22:42:10 INFO - PROCESS | 1666 | 22:42:10 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:75.50 stddev:8.63 (11.4%) stddev-sans-first:9.03 22:42:10 INFO - PROCESS | 1666 | Values: 69.0 68.0 82.0 84.0 84.0 82.0 65.0 65.0 66.0 66.0 22:42:10 INFO - PROCESS | 1666 | -------- Summary: end -------- 22:42:10 INFO - PROCESS | 1666 | 22:42:10 INFO - TEST-INFO | 1666: exit 0 22:42:10 INFO - TEST-OK | kraken | took 59854ms 22:42:10 INFO - SUITE-END | took 1176s 22:42:11 INFO - Completed test suite (00:19:37) 22:42:11 INFO - javascript benchmark 22:42:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2668.826526275792, 3396.0491062502506, 3399.1841957930087, 3424.2980189061245, 3460.5501284832985, 5580.0, 5652.491275785179, 5691.601587888849, 5771.307443106828, 5792.855178620536, 5686.914696279559, 5736.053551164053, 5747.913781293273, 5750.856228594287, 5754.712264386785, 5166.819161329354, 5210.7655155517905, 5233.319668443097, 5246.947530524703, 5302.151655735083, 4297.570242975708, 5320.467953204688, 5337.279467271907, 5343.358796944372, 5346.010987967219, 5158.9742051289695, 5159.845204643867, 5178.818741344054, 5185.611079169059, 5317.2821669074565, 5221.477852214767, 5239.633225674204, 5253.343332083489, 5259.947400526023, 5318.468153184689, 5127.66670166438, 5134.537891589737, 5154.226865970097, 5178.223266510016, 5181.1969144782615, 5637.774489020434, 5672.858178545528, 5731.570132240057, 5735.139729040636, 5745.511631511326, 6316.0, 6320.841978950563, 6336.20797400325, 6338.461230795388, 6339.334369891141, 5612.971935140339, 6074.967255566565, 6264.310925798159, 6323.6521991290465, 6334.049892516114, 4921.335619691367, 5847.415258474119, 5925.051991681354, 5930.377310382434, 5938.079597662369, 6329.936700632935, 6330.39861213184, 6332.620042797447, 6336.809895703136, 6340.619562826153, 6031.336552979169, 6095.66473843934, 6102.176206212193, 6201.224846894138, 6234.844128896723, 5976.581639285295, 6255.624662520263, 6270.467010304175, 6298.1812364392335, 6320.747170113144, 6182.721777519932, 6260.123582698426, 6268.529860260499, 6273.843153921097, 6274.309825919146, 6097.040762675633, 6249.218847644044, 6282.591631543935, 6293.96853015732, 6308.463780578702, 4916.188828843201, 4960.553550180501, 4961.82633607822, 4968.453470118284, 4969.975150124226, 4408.7575183365225, 4474.7762611869275, 4486.573775491323, 4494.033782736662, 4521.909561808746, 5778.3643799182055, 5789.710514474259, 5805.767769289266, 5850.678212698257, 5855.180274761537, 6260.280067792262, 6284.968575157095, 6330.556861019684, 6344.587601805868, 6367.395097465734, 5647.774089036392, 5702.600817942704, 5704.629199102045, 5790.478856902899, 5831.300243970751, 5453.754581043862, 5455.5908306877145, 5472.288602481652, 5486.615936884459, 5513.917291240635, 5284.735763211824, 6248.0, 6297.716602752796, 6305.4009869062365, 6320.27316858558, 6190.628562286277, 6240.282367527792, 6331.651759153291, 6337.4613157882095, 6344.143540622142, 6301.495880329471, 6364.809055728336, 6396.680165991775, 6398.520110991787, 6404.327545607644, 6246.750129994749, 6246.968765156145, 6258.43674069336, 6277.466415354564, 6283.71723272463, 5963.731632076654, 6187.566870319034, 6303.716332765129, 6312.368763123651, 6323.462505687068, 6136.140940268277, 6136.815895523141, 6169.81490555284, 6202.317745048132, 6203.2245969253845, 6134.447899689049, 6158.0, 6175.320714721467, 6178.011518157074, 6197.442230199304, 4963.503649635008, 5294.0, 5887.1757953885635, 6007.098935159586, 6029.547783916136, 5171.767270472913, 6089.238845144357, 6147.293061297829, 6159.5688301818445, 6173.783917562864, 5024.974875125601, 5965.642061476326, 5970.58205925581, 5981.551383646331, 6002.879942401215], "value": 5697.612481246002, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.8865216674081, 483.4609410507283, 526.2211926349004, 532.0263917031831, 546.9179623056543, 30488.695113048863, 42422.36366454502, 43271.99999999996, 43383.78308108455, 45378.31932521015, 43631.0, 44082.55917440825, 44125.117497650026, 44218.33672494907, 44340.778296108474, 9728.708138755814, 9890.604375824958, 9913.909469958298, 9914.900850991506, 9917.157041651468, 8799.824003519925, 9021.413608115485, 11924.582639607615, 12167.391630418488, 12188.451519681636, 12193.695157621087, 12294.938525307361, 12296.69258268546, 12308.015358771321, 12327.445264963098, 8858.557072146366, 12040.638780836545, 12168.452419641137, 12169.878301216962, 12198.390080496029, 11690.590829320932, 12034.398280085961, 12061.517539298418, 12286.0, 12315.815262771066, 41294.38058429126, 41758.58241417578, 41786.79106604478, 41788.58211417908, 41793.58206417958, 3629.2560025195035, 3763.8682646107254, 3763.9811800941093, 3870.3420418528635, 4086.2849001424634, 27637.032703855468, 27648.723512765016, 27659.861700691366, 27687.446251074867, 27697.86151069252, 3189.186757376854, 3234.2884565395575, 3431.3137372525343, 3439.8968030959113, 3458.9827050864583, 462637.0, 463973.0, 465345.0, 467009.0, 467384.0, 60250.09624855631, 60694.0, 60762.088568671505, 60848.69575652182, 60896.78206435935, 63092.369076309566, 63180.0, 63193.0, 63239.36760632427, 63249.36750632527, 13759.724805503834, 16700.914440561326, 16703.832961670472, 16732.24704888283, 16736.832631673526, 11803.350815705218, 11972.64082077539, 12925.741485170243, 13031.218126912227, 13031.609051728463, 15746.0, 16108.75836862448, 16210.59473513148, 16284.267207975432, 16547.503574892773, 5776.740046697824, 6387.297397286295, 6804.591724496546, 6805.863882722318, 6944.409725173316, 739.3567596191348, 882.2853488674194, 975.0541974284932, 986.9703908882746, 1107.6597317246221, 29329.85335073311, 31023.84488077545, 31144.844275778476, 31507.21231969173, 32024.0, 840.9466372086484, 914.9588268527932, 965.6577357473118, 1143.9180691142208, 1145.2269717940358, 15157.848421515864, 15564.766528502081, 15610.375584976473, 15829.050256984618, 15972.281247343897, 628413.0, 630627.0, 633388.0, 633647.0, 633997.0, 57630.7118464405, 57710.422895770505, 57844.42155578474, 57873.710631447415, 57996.8400631985, 1267.6577324122436, 1397.7064816388672, 1415.8938079644067, 1430.5851303121979, 1463.3488097796378, 30971.0, 31121.910733125136, 31311.53032704557, 31831.681683183797, 32206.0, 50271.49728502668, 51016.23475647794, 51051.74474127606, 51262.48737512726, 51296.0, 470132.0, 473763.0, 477167.0, 478847.0, 478900.0, 26194.0, 27128.05051823177, 27224.455510889275, 27949.882004719584, 27982.0, 49025.754871225414, 49132.75433622809, 50150.74924625499, 50222.2466662993, 50274.24588631246, 40314.798426008856, 40889.0, 41229.96925076897, 41637.375439367825, 41820.58179418167, 39374.07824021997, 41729.37405938974, 44516.66450006505, 45480.0, 46068.30897536473, 36748.63251367559, 41558.0, 42392.36411453893, 44028.33957490576, 44113.558864410945, 42655.78672106619, 43120.0, 44268.778656106515, 44997.77501112474, 45378.0, 31498.842505787325, 31561.12360778012, 33442.83278583591, 33448.498272525445, 34119.65880341165], "value": 20730.51012919929, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.2395906616572, 361.4596178712825, 382.1077783375819, 389.65320864430663, 411.6048593350383, 15738.92130539347, 15953.0, 18820.62358752824, 20543.767373957573, 23098.0, 13853.238071906042, 20543.691844622343, 21218.681719774217, 21356.0, 21412.25057122997, 5325.627206095575, 5577.330720313568, 5582.246396736429, 5585.385607583163, 5590.18942253373, 3947.7039222058315, 4481.932771008437, 5010.649254552164, 5244.1347177715825, 7099.219085900547, 7048.400885924677, 7061.929380706204, 7066.787996360117, 7084.893726594079, 7086.787396378091, 6854.348836860466, 6939.653017349112, 7015.508914375996, 7034.19106802714, 7043.154821421411, 5808.099744539603, 5917.970410147922, 6184.196054512884, 6197.16338294329, 6199.56603037783, 13788.172709637454, 13830.861691383057, 13899.16605003703, 13965.882729381625, 23094.884525577432, 2168.5761047463157, 2417.359399759065, 2443.0, 2481.0819996601194, 2998.025641666467, 10200.79598408035, 10672.519736611872, 11044.668659940215, 11408.0, 16556.006639601543, 1898.8765730227492, 1962.4210857796984, 1964.666006778851, 1986.7317912081828, 2011.5675129847007, 121548.56902861893, 122255.77744222533, 123946.0, 125424.0, 127798.0, 10504.369737815756, 11050.33697978124, 11225.71935701614, 11669.708257293465, 11708.590199342982, 11681.240719353385, 13729.382177802023, 14640.853591463949, 14693.92653036728, 14942.25288735559, 8137.715179968673, 8239.711610093618, 8293.129221431784, 8304.916950830415, 8416.4950102994, 6407.583507072025, 6411.326810684905, 8067.636956336979, 8071.798205044921, 8105.837883242302, 4535.183666939983, 4628.629709623222, 4639.953600463952, 4691.0, 4711.693739906964, 1657.651893102462, 1665.6502134551638, 1932.6327997680396, 1981.6532106881239, 1982.098145343865, 841.7054031089192, 893.446063440671, 925.2597921662777, 942.1473566422399, 958.9520523973773, 21627.567448650938, 22051.448713782283, 22051.448713782283, 22080.889595552242, 22291.331260062223, 914.0904799724317, 928.5682157796522, 948.6964171465203, 957.4590356448584, 1004.1414590525142, 5146.022255771391, 5991.400859914061, 6489.70796314167, 11048.613298534512, 11540.422978851191, 451741.0, 556529.0, 572266.0, 576337.0, 580504.0, 89701.0, 135045.0, 138002.999999998, 146193.0, 151930.24034879755, 450.93236014597414, 469.28199854223095, 470.5130190253072, 471.14016919122736, 473.01612645696866, 11297.830532542182, 11651.59219427316, 12008.699782505508, 12047.457864395948, 12065.819012714992, 44469.77765111154, 58174.709126454094, 58248.0, 58881.41118588931, 59158.40841591529, 67638.66180669262, 85987.0, 86629.56685216534, 86651.56674216589, 87159.56420217859, 7611.2769286917655, 7622.885656715264, 7775.533467991939, 7876.881846772418, 8081.15147909461, 10048.648297309852, 11560.94219528897, 11997.76004479888, 12046.698832528906, 12065.215760975509, 8504.702335418231, 9836.704898853046, 10135.695929122137, 10341.534630941474, 10357.17142628603, 9923.255755818487, 9936.0, 9945.751356216153, 10297.794044119226, 10434.582616695247, 10012.84980725275, 10248.795024099625, 10267.973202679672, 10301.072903438726, 10593.470326483492, 7619.69521219145, 7842.29419352261, 7876.8818467721885, 7891.84216315659, 8077.313428358655, 7808.0, 9090.59092340833, 10339.224558158017, 10418.635347762793, 10444.112250458797], "value": 9000.681150650404, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1393.3869097597062, 1551.9922400388, 1606.2450648195345, 1785.8660600454969, 1923.14420083063, 7904.0, 8623.0, 8901.643934242622, 8927.241184499317, 9019.052999435064, 8803.823923521542, 8898.466092034483, 8913.643454261823, 8919.95540022299, 8928.419652722569, 2399.84401013934, 2434.196715084018, 2438.207582535674, 2442.438239204984, 2445.0953147335513, 2386.95226095478, 2424.9272521824373, 2442.096424323003, 2446.7430919753438, 2448.069733501276, 2387.8209134314907, 2390.127603424755, 2407.313915534073, 2431.7689819467123, 2437.878106094697, 2367.9052837886466, 2396.6405039244164, 2405.987970060156, 2412.674288970984, 2415.7342692303832, 2369.324742448394, 2376.4415362389823, 2399.076355603088, 2400.8319417640773, 2401.0, 8910.465372077697, 9008.009118996904, 9025.819483610358, 9025.864612030824, 9027.277817774628, 5291.391489978663, 5487.807926722546, 5496.203050557647, 5532.834014979557, 5542.168674698787, 3706.962930370689, 3725.590185079639, 3775.528058992626, 3780.073881898925, 3782.0355809268735, 938.5213541094023, 940.6472572785206, 1095.189559725805, 1107.4850194659523, 1117.0895719988264, 11847.289162650268, 11911.940440297829, 12016.038716902714, 12064.09519286057, 12072.336021518813, 4508.549145085465, 4647.024124933734, 4732.2428411454, 4734.313524538922, 4745.9050818984115, 9485.810283794424, 9486.098820612031, 9506.287028472892, 9506.762330941781, 9524.619015239312, 5372.167314066326, 5381.219723140122, 5381.623286369917, 5393.919091213635, 5394.3796463406425, 4335.263005289108, 4377.802998865059, 4459.888502787391, 4988.0, 5031.949680503221, 3846.0, 3865.323568375523, 3972.980135099364, 3975.105601239698, 3982.4623675803973, 1020.6937918624383, 1182.131133616791, 1185.549491193341, 1188.9227200231958, 1195.7369378736664, 817.8609636361833, 879.520661239626, 881.5239770523846, 910.403685585929, 956.6125719083782, 5994.460498555151, 6081.300650425169, 6235.719392627343, 6237.719302631393, 6259.530535209878, 950.4772375193754, 974.6491263145263, 1003.9598416063421, 1006.6325791086201, 1034.5701465392472, 3321.534985102088, 3863.092173339263, 3937.0, 4047.919041619151, 4117.855875044359, 10883.83674244887, 11198.992090711876, 11209.439528023566, 11234.0, 11242.718932026764, 11043.282186658002, 11164.218504704593, 11181.608643697431, 11186.832197517044, 11190.776184476428, 7885.842283154305, 8229.423940324239, 8354.749357519284, 8495.74512764618, 8495.872561911576, 6063.151158837678, 6200.534959878118, 6253.7185826637, 6271.78048768291, 6324.462420694109, 10023.69928902134, 10249.538770755185, 10278.794424111325, 10280.897191027994, 10296.021877921585, 9365.578548965177, 9593.28050396237, 9672.564734587102, 10037.397756134655, 10038.447885366082, 7639.197884222077, 7820.139784623573, 7898.486598371088, 7901.762947111596, 7915.564643944638, 7318.487705860539, 8674.609642565974, 8766.780830479289, 8772.824543508967, 8787.209151176425, 7724.8455030900195, 7809.062912450542, 7860.84278314442, 7869.291763741291, 8029.558374289237, 7556.8866467001935, 7631.3513351365755, 7659.080910290801, 7741.41939354562, 7883.448158628841, 7104.715811367488, 7257.782266532013, 7257.818554536178, 7270.0, 7274.96362518184, 5046.728971962558, 5159.426374955675, 5245.475452454724, 5248.186531087687, 5267.525922666979, 4241.257779888569, 5107.438181799925, 5112.054269960069, 5316.946830531645, 5378.408375078817], "value": 4668.514302578044, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.68369553816274, 303.1678043769852, 312.6685713144067, 319.0492332848111, 322.60964233277736, 4634.5597168269005, 5235.0, 5276.868078298045, 5330.546903513201, 5417.945820541793, 4145.191687620915, 4377.890552736183, 4986.4514903360605, 5102.77037533311, 5201.921971170435, 4021.0349516116066, 4027.375756757707, 4035.737677050997, 4047.959520404788, 4070.6770299652585, 3819.8090095495254, 3830.0616348994536, 4039.394090886376, 4077.694172937027, 4083.4278802661042, 3697.3899306614376, 3810.161764411825, 4024.134811015631, 4028.0131367814924, 4040.2121586290687, 3247.350529894031, 3491.284286721216, 3513.5608048993877, 3735.2529494101154, 4034.677225821927, 3195.7762956593047, 3261.037993791837, 3525.189206482498, 3561.7506774525796, 3601.405768048269, 4917.655764096515, 5177.275181474596, 5184.248283998799, 5198.40218374888, 5215.243789650517, 4113.609207125318, 4421.668374871865, 4639.466461356953, 4667.9766601166775, 4690.413698287714, 4839.249916262949, 5531.225628412026, 5539.723013849291, 5546.084895992157, 5546.696526316313, 3272.9509057364157, 3421.555197824292, 3923.8430462781453, 3924.3917192835156, 3958.604139586047, 5681.7500149966945, 5868.941310586925, 5871.884341975011, 5917.319508256563, 5918.348981612019, 3278.6885245901603, 3888.4361767544115, 3985.5416627087675, 3990.6009399059863, 4018.7588744675413, 4038.7778672173313, 4039.939400908989, 4046.9275641954905, 4049.3318602430268, 4063.309237429644, 4043.130726893673, 4254.383114448449, 4490.39379683745, 4611.584957353854, 4647.767611619405, 5109.0, 5159.974200128975, 5584.97207513968, 5615.410381909923, 5637.577181711388, 1228.7235372041218, 1300.6033159886135, 1343.8320209973754, 1384.882285005786, 1387.1399732166121, 811.7321283976273, 874.8993865705398, 877.828099487179, 882.7572417585216, 902.7066203483894, 4091.590840915944, 4843.602416803635, 5021.748912554358, 5036.874078148002, 5126.794928202905, 4940.950590494121, 5668.914966275509, 5676.744546495418, 5702.0, 5704.030314846486, 4323.535584716744, 4893.69675469057, 5113.3097031900215, 5114.5652619527755, 5124.718140502308, 2526.50733107046, 2834.7243740316526, 2903.666078401011, 2931.736143747073, 3008.6611457901463, 5024.563086902849, 5157.948420515747, 5705.74324155414, 5725.312962444534, 5801.245838041028, 5454.12733962572, 5487.368952570426, 5791.768329266866, 5801.767929282866, 5943.524518038546, 4688.5619477872, 4766.879783251003, 4790.610722890392, 6054.424829641177, 6064.787732429256, 4858.562729354375, 5428.70142142186, 5774.249347584787, 5807.709614519342, 5845.649261044351, 4924.359833221658, 5445.782168713207, 5718.51392631631, 5732.455416735404, 5762.049261871744, 5414.377346605191, 6005.849853753691, 6006.759729610942, 6028.487578555696, 6035.943709850846, 3397.711194548392, 3578.141246100969, 3892.4939757831303, 3903.10228647407, 3907.5701672816535, 4927.482614325444, 5476.576090216652, 5761.596688231783, 5778.884422311614, 5791.220214297076, 4392.780360982002, 4995.325631039908, 5079.339685840817, 5203.895922081613, 5472.890542189213, 4578.679492435631, 4579.427571553556, 5315.654482458628, 5442.23808666779, 5544.006236632174, 5381.811636592701, 5440.374356949002, 5441.865782554025, 5459.508644222039, 5513.338399392099, 5299.973500132474, 5582.520632032433, 5595.608307418442, 5631.662100273997, 5631.802886898939, 4993.925091123709, 5321.760520776497, 5482.643628164316, 5503.752331145028, 5526.585506086979], "value": 4038.1125524739496, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.825010247643, 765.4029856711765, 785.089296416157, 789.668339297495, 833.2468456714159, 15199.316030778615, 16649.750253746188, 16665.00009999398, 16672.499825005238, 16727.080010599428, 15017.62455938602, 15202.847971520281, 15203.695926081471, 15205.771913421308, 15247.61880952974, 694.3230350408362, 715.4419552748839, 876.4872549558515, 879.199928065459, 904.6607522179182, 890.6437425030008, 891.4294851295176, 904.9049849765746, 905.4386280506095, 907.5598334807613, 961.2598299309527, 963.0995019656643, 965.1844191658038, 966.1015255812092, 967.762898089969, 738.4646131554633, 920.3833431600843, 955.4362925873768, 956.4261443134098, 956.7129861041694, 707.745211723779, 708.7023450150949, 710.9146902371748, 711.5588335232189, 711.8398360368927, 15446.768298475532, 15470.071795692294, 15483.303251353604, 15500.069995800175, 15520.5343839685, 14121.646958826112, 14255.287235638176, 14366.098031341866, 14384.64038399038, 14421.567352979428, 13284.667883302898, 13305.334733263298, 13360.0, 13411.46354145833, 13725.931370343083, 3771.9434208486896, 4630.9073818523775, 4665.6748904220385, 4709.670323077386, 4830.082284365994, 120386.59226815414, 121441.39279303723, 121456.0, 121555.0, 121748.0, 9780.55987480565, 11220.382878941737, 11415.543378264943, 11522.135839812048, 11857.34784586856, 21444.785552144593, 22429.439264018205, 22601.4349641257, 22619.0, 22653.43366415853, 7899.131095579482, 8060.717874874351, 8148.247323505037, 8154.469959452616, 8276.462029968034, 4362.083962367875, 4476.7090139140855, 4520.751358675305, 4547.931781023287, 4634.2585186370015, 3521.207728261121, 3663.8900832975055, 3665.5051568038507, 3667.743257971916, 3668.1196512836727, 645.8030300758314, 654.254150268694, 659.6635715784985, 670.815525730428, 680.3162821364497, 567.2880534928721, 598.8053882488209, 605.8061420345447, 654.7413771560207, 729.6205972894065, 9026.503542305105, 9682.75793105178, 9823.607055717692, 9846.458444785463, 9867.851982220272, 565.9971700141556, 575.3670961941914, 623.1182876230083, 674.4840197249108, 689.7103216649019, 4529.773511324486, 4818.521908703777, 4819.2530157825595, 4831.73425461596, 4855.902881942341, 98967.50516247516, 99285.5107173393, 141508.0, 147145.26427368008, 152232.0, 81529.0, 83141.58429207816, 118199.0, 127826.0, 128737.0, 104925.47537262418, 111481.0, 114677.0, 114745.4262728681, 116114.41942790232, 8886.422382545114, 9171.35800493959, 9189.310801689766, 9194.678186263449, 9196.402233854778, 44095.33856992212, 49317.75341123271, 54773.45226547843, 55941.0, 58143.12785308308, 62567.0, 83425.58287208524, 86277.1372286269, 88534.11465885259, 90028.09971900197, 10750.569977200825, 12306.507739690313, 12741.49034038628, 12768.74462510763, 12943.676408089874, 13195.670108246986, 13386.732265354833, 13521.72956540844, 13572.660683482993, 13726.176429414267, 12589.0, 12599.118061735591, 12652.17760845542, 12667.556635517712, 12872.034597405045, 12542.874571254171, 12608.621741347773, 12712.618621441372, 12726.490940362652, 12888.420021098886, 12486.562970295996, 12493.875061249635, 12524.874751252371, 12935.935320323339, 12944.8058279124, 8869.73390798277, 10235.48822558884, 10668.946655266674, 10746.570137194425, 10748.785024299626, 10650.520726567169, 10764.3541387517, 10859.674209773719, 10891.638545181851, 10897.564097436014], "value": 7805.3428264964705, "unit": "score"}], "name": "dromaeo_css", "value": 7340.556785926485, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 112.0, 112.0, 113.0, 111.0, 113.0, 110.0, 113.0, 112.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 117.0, 108.0, 162.0, 119.0, 106.0, 129.0, 118.0, 142.0, 109.0], "value": 125.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 181.0, 184.0, 182.0, 183.0, 174.0, 181.0, 187.0, 181.0, 183.0], "value": 182.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 57.0, 58.0, 107.0, 59.0, 57.0, 66.0, 68.0, 85.0, 56.0], "value": 69.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 70.0, 122.0, 101.0, 116.0, 68.0, 96.0, 69.0, 69.0, 102.0], "value": 89.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 88.0, 89.0, 90.0, 134.0, 111.0, 89.0, 85.0], "value": 94.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 86.0, 84.0, 83.0, 83.0, 84.0, 85.0, 83.0], "value": 84.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 52.0, 53.0, 53.0, 53.0, 53.0, 53.0, 54.0, 52.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 74.0, 71.0, 76.0, 72.0, 70.0, 71.0, 72.0, 71.0, 72.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 89.0, 100.0, 101.0, 77.0, 84.0, 81.0, 90.0, 100.0, 102.0], "value": 90.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 147.0, 151.0, 136.0, 136.0, 134.0, 136.0, 134.0, 220.0, 136.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 160.0, 162.0, 161.0, 163.0, 162.0, 162.0, 163.0, 160.0], "value": 161.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 82.0, 84.0, 84.0, 82.0, 65.0, 65.0, 66.0, 66.0], "value": 73.1, "unit": "score"}], "name": "kraken", "value": 1448.1, "alertThreshold": 2.0}]} 22:42:11 INFO - Return code: 0 22:42:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:42:11 INFO - # TBPL SUCCESS # 22:42:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:42:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:42:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:42:11 INFO - 2016-07-21 22:42:11.371 screenresolution[1670:10418] starting screenresolution argv=screenresolution get 22:42:11 INFO - 2016-07-21 22:42:11.374 screenresolution[1670:10418] Display 0: 1600x1200x32@60 22:42:11 INFO - 2016-07-21 22:42:11.379 screenresolution[1671:10422] starting screenresolution argv=screenresolution list 22:42:11 INFO - Available Modes on Display 0 22:42:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:42:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:42:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:42:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:42:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:42:11 INFO - Intel Iris: 22:42:11 INFO - Chipset Model: Intel Iris 22:42:11 INFO - Type: GPU 22:42:11 INFO - Bus: Built-In 22:42:11 INFO - VRAM (Dynamic, Max): 1536 MB 22:42:11 INFO - Vendor: Intel (0x8086) 22:42:11 INFO - Device ID: 0x0a2e 22:42:11 INFO - Revision ID: 0x0009 22:42:11 INFO - Displays: 22:42:11 INFO - Display: 22:42:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:42:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:42:11 INFO - Display Serial Number: 1600x1200 60 22:42:11 INFO - Main Display: Yes 22:42:11 INFO - Mirror: Off 22:42:11 INFO - Online: Yes 22:42:11 INFO - Rotation: Supported 22:42:11 INFO - Return code: 0 22:42:11 INFO - Running post-action listener: _resource_record_post_action 22:42:11 INFO - [mozharness: 2016-07-22 05:42:11.624740Z] Finished run-tests step (success) 22:42:11 INFO - Running post-run listener: _resource_record_post_run 22:42:11 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 32693248; Write bytes: 708065792; Read time: 321; Write time: 1923 22:42:11 INFO - TinderboxPrint: CPU usage
53.3% 22:42:11 INFO - TinderboxPrint: I/O read bytes / time
32,693,248 / 321 22:42:11 INFO - TinderboxPrint: I/O write bytes / time
708,065,792 / 1,923 22:42:11 INFO - TinderboxPrint: CPU idle
2,264.2 (46.7%) 22:42:11 INFO - TinderboxPrint: CPU system
967.5 (19.9%) 22:42:11 INFO - TinderboxPrint: CPU user
1,621.9 (33.4%) 22:42:11 INFO - TinderboxPrint: Swap in / out
436,113,408 / 0 22:42:11 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 405680640; Write bytes: 399681536; Read time: 30838; Write time: 784 22:42:11 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 29129728; Write bytes: 296645120; Read time: 290; Write time: 1107 22:42:12 INFO - Running post-run listener: _upload_blobber_files 22:42:12 INFO - Blob upload gear active. 22:42:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:42:12 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 22:42:12 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:42:12 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:42:12 INFO - (blobuploader) - INFO - Open directory for files ... 22:42:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:42:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:42:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:42:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:42:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:42:13 INFO - (blobuploader) - INFO - Done attempting. 22:42:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:42:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:42:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:42:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:42:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:42:14 INFO - (blobuploader) - INFO - Done attempting. 22:42:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:42:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:42:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:42:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:42:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:42:18 INFO - (blobuploader) - INFO - Done attempting. 22:42:18 INFO - (blobuploader) - INFO - Iteration through files over. 22:42:18 INFO - Return code: 0 22:42:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:42:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:42:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3f049e067019fc4a0dffe620de02edd5910a9cff4c6c5b6f3422bf59acbadcf5765009136a77472c080b76a717adb71daf0a24c201631900da583f5b8b6d4e5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9b88a018e507e74e2cf5834ce0b25f7a5574cad3735a1a5df40f3f6201ce247abd5628e3b109fe80373a232cb358fdc8971c0d175cfa437a7f54e87a3b3278a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fcdfc17b1aa6485a75ec7a6441a0afcf9c85777582bb9b716d2241dfe1dde3ac63d3810c2e34a8518d7ec4e92bc60125cc1044f5d60555f9b013f61d266e34be"} 22:42:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:42:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:42:18 INFO - Contents: 22:42:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3f049e067019fc4a0dffe620de02edd5910a9cff4c6c5b6f3422bf59acbadcf5765009136a77472c080b76a717adb71daf0a24c201631900da583f5b8b6d4e5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9b88a018e507e74e2cf5834ce0b25f7a5574cad3735a1a5df40f3f6201ce247abd5628e3b109fe80373a232cb358fdc8971c0d175cfa437a7f54e87a3b3278a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fcdfc17b1aa6485a75ec7a6441a0afcf9c85777582bb9b716d2241dfe1dde3ac63d3810c2e34a8518d7ec4e92bc60125cc1044f5d60555f9b013f61d266e34be"} 22:42:18 INFO - Running post-run listener: copy_logs_to_upload_dir 22:42:18 INFO - Copying logs to upload dir... 22:42:18 INFO - mkdir: /builds/slave/test/build/upload/logs 22:42:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.851975 ========= master_lag: 0.51 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-07-21 22:42:18.868719) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 22:42:18.873330) ========= 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.JRnS1oRoLy/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.rGxSuAmlwC/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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/Autoland/sha512/3f049e067019fc4a0dffe620de02edd5910a9cff4c6c5b6f3422bf59acbadcf5765009136a77472c080b76a717adb71daf0a24c201631900da583f5b8b6d4e5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9b88a018e507e74e2cf5834ce0b25f7a5574cad3735a1a5df40f3f6201ce247abd5628e3b109fe80373a232cb358fdc8971c0d175cfa437a7f54e87a3b3278a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fcdfc17b1aa6485a75ec7a6441a0afcf9c85777582bb9b716d2241dfe1dde3ac63d3810c2e34a8518d7ec4e92bc60125cc1044f5d60555f9b013f61d266e34be"} build_url:https://queue.taskcluster.net/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010949 build_url: 'https://queue.taskcluster.net/v1/task/EXym94w4RWy4mPitNYoGyg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3f049e067019fc4a0dffe620de02edd5910a9cff4c6c5b6f3422bf59acbadcf5765009136a77472c080b76a717adb71daf0a24c201631900da583f5b8b6d4e5e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9b88a018e507e74e2cf5834ce0b25f7a5574cad3735a1a5df40f3f6201ce247abd5628e3b109fe80373a232cb358fdc8971c0d175cfa437a7f54e87a3b3278a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fcdfc17b1aa6485a75ec7a6441a0afcf9c85777582bb9b716d2241dfe1dde3ac63d3810c2e34a8518d7ec4e92bc60125cc1044f5d60555f9b013f61d266e34be"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 22:42:18.914356) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 22:42:18.914681) ========= 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.JRnS1oRoLy/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.rGxSuAmlwC/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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.005084 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 22:42:18.962636) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 22:42:18.965969) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 22:42:18.966284) ========= ========= Total master_lag: 0.71 =========