builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0239 starttime: 1470758693.54 results: success (0) buildid: 20160809064419 builduid: a6589aed923947f292a7168d3cc8a142 revision: 0813514a828bf931605bd22596d8fe876c1bb368 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 09:04:53.547777) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-09 09:04:53.548571) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 09:04:53.548935) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-09 09:04:53.573979) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 09:04:53.574350) ========= 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.AWs7OVi6Yq/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.NDvhIEhgcs/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-09 09:04:53-- 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% 19.0M=0.001s 2016-08-09 09:04:54 (19.0 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.441697 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 09:04:54.038980) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 09:04:54.039372) ========= 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.AWs7OVi6Yq/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.NDvhIEhgcs/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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.026202 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 09:04:54.088532) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 09:04:54.088942) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0813514a828bf931605bd22596d8fe876c1bb368 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0813514a828bf931605bd22596d8fe876c1bb368 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AWs7OVi6Yq/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.NDvhIEhgcs/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-09 09:04:54,173 truncating revision to first 12 chars 2016-08-09 09:04:54,173 Setting DEBUG logging. 2016-08-09 09:04:54,173 attempt 1/10 2016-08-09 09:04:54,173 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0813514a828b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-09 09:04:54,904 unpacking tar archive at: mozilla-central-0813514a828b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.014095 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-09 09:04:55.125814) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 09:04:55.126146) ========= script_repo_revision: 0813514a828bf931605bd22596d8fe876c1bb368 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-09 09:04:55.126614) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 09:04:55.126946) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-09 09:04:55.146353) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-08-09 09:04:55.146841) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AWs7OVi6Yq/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.NDvhIEhgcs/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:04:55 INFO - MultiFileLogger online at 20160809 09:04:55 in /builds/slave/test 09:04:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:04:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:04:55 INFO - {'append_to_log': False, 09:04:55 INFO - 'base_work_dir': '/builds/slave/test', 09:04:55 INFO - 'blob_upload_branch': 'Firefox', 09:04:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:04:55 INFO - 'branch': 'Firefox', 09:04:55 INFO - 'buildbot_json_path': 'buildprops.json', 09:04:55 INFO - 'config_files': ('talos/mac_config.py',), 09:04:55 INFO - 'default_actions': ('clobber', 09:04:55 INFO - 'read-buildbot-config', 09:04:55 INFO - 'download-and-extract', 09:04:55 INFO - 'populate-webroot', 09:04:55 INFO - 'create-virtualenv', 09:04:55 INFO - 'install', 09:04:55 INFO - 'run-tests'), 09:04:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:04:55 INFO - 'download_minidump_stackwalk': True, 09:04:55 INFO - 'download_symbols': 'ondemand', 09:04:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:04:55 INFO - 'tooltool.py': '/tools/tooltool.py', 09:04:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:04:55 INFO - '/tools/misc-python/virtualenv.py')}, 09:04:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:04:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:04:55 INFO - 'installer_path': 'installer.exe', 09:04:55 INFO - 'log_level': 'info', 09:04:55 INFO - 'log_name': 'talos', 09:04:55 INFO - 'log_to_console': True, 09:04:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:04:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:04:55 INFO - 'opt_config_files': (), 09:04:55 INFO - 'pip_index': False, 09:04:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:04:55 INFO - 'cmd': ('bash', 09:04:55 INFO - '-c', 09:04:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:04:55 INFO - 'enabled': True, 09:04:55 INFO - 'halt_on_failure': False, 09:04:55 INFO - 'name': 'check_screen_resolution'},), 09:04:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:04:55 INFO - 'cmd': ('bash', 09:04:55 INFO - '-c', 09:04:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:04:55 INFO - 'enabled': True, 09:04:55 INFO - 'halt_on_failure': False, 09:04:55 INFO - 'name': 'check_screen_resolution'},), 09:04:55 INFO - 'run_cmd_checks_enabled': True, 09:04:55 INFO - 'sps_profile': False, 09:04:55 INFO - 'sps_profile_interval': 0, 09:04:55 INFO - 'suite': 'dromaeojs', 09:04:55 INFO - 'system_bits': '32', 09:04:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:04:55 INFO - 'title': 't-yosemite-r7-0239', 09:04:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:04:55 INFO - 'use_talos_json': True, 09:04:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:04:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:04:55 INFO - 'work_dir': 'build'} 09:04:55 INFO - [mozharness: 2016-08-09 16:04:55.359995Z] Running clobber step. 09:04:55 INFO - Running pre-action listener: _resource_record_pre_action 09:04:55 INFO - Running main action method: clobber 09:04:55 INFO - rmtree: /builds/slave/test/build 09:04:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:04:57 INFO - Running post-action listener: _resource_record_post_action 09:04:57 INFO - [mozharness: 2016-08-09 16:04:57.414408Z] Finished clobber step (success) 09:04:57 INFO - [mozharness: 2016-08-09 16:04:57.414570Z] Running read-buildbot-config step. 09:04:57 INFO - Running pre-action listener: _resource_record_pre_action 09:04:57 INFO - Running main action method: read_buildbot_config 09:04:57 INFO - Using buildbot properties: 09:04:57 INFO - { 09:04:57 INFO - "project": "", 09:04:57 INFO - "product": "firefox", 09:04:57 INFO - "script_repo_revision": "production", 09:04:57 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:04:57 INFO - "repository": "", 09:04:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:04:57 INFO - "buildid": "20160809064419", 09:04:57 INFO - "pgo_build": "False", 09:04:57 INFO - "basedir": "/builds/slave/test", 09:04:57 INFO - "buildnumber": 29, 09:04:57 INFO - "slavename": "t-yosemite-r7-0239", 09:04:57 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:04:57 INFO - "platform": "yosemite_r7", 09:04:57 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:04:57 INFO - "branch": "mozilla-central", 09:04:57 INFO - "repo_path": "mozilla-central", 09:04:57 INFO - "revision": "0813514a828bf931605bd22596d8fe876c1bb368", 09:04:57 INFO - "stage_platform": "macosx64", 09:04:57 INFO - "builduid": "a6589aed923947f292a7168d3cc8a142", 09:04:57 INFO - "slavebuilddir": "test" 09:04:57 INFO - } 09:04:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:04:57 INFO - Running post-action listener: _resource_record_post_action 09:04:57 INFO - [mozharness: 2016-08-09 16:04:57.418119Z] Finished read-buildbot-config step (success) 09:04:57 INFO - [mozharness: 2016-08-09 16:04:57.418225Z] Running download-and-extract step. 09:04:57 INFO - Running pre-action listener: _resource_record_pre_action 09:04:57 INFO - Running main action method: download_and_extract 09:04:57 INFO - mkdir: /builds/slave/test/build/tests 09:04: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:57 INFO - trying https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:04:57 INFO - Downloading https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:04:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 09:04:58 INFO - Downloaded 1199 bytes. 09:04:58 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:04:58 INFO - Using the following test package requirements: 09:04:58 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:04:58 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:04:58 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:04:58 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:04:58 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:04:58 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:04:58 INFO - u'jsshell-mac.zip'], 09:04:58 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:04:58 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:04:58 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:04:58 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:04:58 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:04:58 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:04:58 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:04:58 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:04:58 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:04:58 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:04:58 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:04:58 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:04:58 INFO - trying https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:04:58 INFO - Downloading https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:04:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:04:59 INFO - Downloaded 16324563 bytes. 09:04:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:04:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:05:02 INFO - Return code: 0 09:05:02 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:05:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:02 INFO - trying https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:05:02 INFO - Downloading https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:05:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:05:03 INFO - Downloaded 16324563 bytes. 09:05:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:05:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:05:06 INFO - Return code: 0 09:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:06 INFO - trying https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:05:06 INFO - Downloading https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:05:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:05:08 INFO - Downloaded 14413723 bytes. 09:05:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:05:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:05:08 INFO - Return code: 0 09:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:08 INFO - trying https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:05:08 INFO - Downloading https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:05:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 09:05:11 INFO - Downloaded 111953263 bytes. 09:05:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:05:11 INFO - mkdir: /builds/slave/test/properties 09:05:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:05:11 INFO - Writing to file /builds/slave/test/properties/build_url 09:05:11 INFO - Contents: 09:05:11 INFO - build_url:https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:05:11 INFO - Running post-action listener: _resource_record_post_action 09:05:11 INFO - Running post-action listener: set_extra_try_arguments 09:05:11 INFO - [mozharness: 2016-08-09 16:05:11.942004Z] Finished download-and-extract step (success) 09:05:11 INFO - [mozharness: 2016-08-09 16:05:11.942148Z] Running populate-webroot step. 09:05:11 INFO - Running pre-action listener: _resource_record_pre_action 09:05:11 INFO - Running main action method: populate_webroot 09:05:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:05:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:05:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:05:11 INFO - '--tppagecycles', 09:05:11 INFO - '7'], 09:05:11 INFO - 'tests': ['tsvgm']}}, 09:05:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:05:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:05:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:05:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:05:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:05:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:05:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:11 INFO - 'talos_options': ['--disable-e10s'], 09:05:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:05:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:05:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:11 INFO - 'talos_options': ['--disable-e10s'], 09:05:11 INFO - 'tests': ['damp', 'tps']}, 09:05:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:11 INFO - 'tests': ['damp', 'tps']}, 09:05:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:05:11 INFO - 'tests': ['dromaeo_dom']}, 09:05:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:05:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:05:11 INFO - 'tests': ['basic_compositor_video']}, 09:05:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:05:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:05:11 INFO - 'tests': ['a11yr', 09:05:11 INFO - 'ts_paint', 09:05:11 INFO - 'tpaint', 09:05:11 INFO - 'sessionrestore', 09:05:11 INFO - 'sessionrestore_no_auto_restore', 09:05:11 INFO - 'tabpaint']}, 09:05:11 INFO - 'other-e10s': {'tests': ['a11yr', 09:05:11 INFO - 'ts_paint', 09:05:11 INFO - 'tpaint', 09:05:11 INFO - 'sessionrestore', 09:05:11 INFO - 'sessionrestore_no_auto_restore', 09:05:11 INFO - 'tabpaint']}, 09:05:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:05:11 INFO - 'ts_paint', 09:05:11 INFO - 'tpaint', 09:05:11 INFO - 'sessionrestore', 09:05:11 INFO - 'sessionrestore_no_auto_restore', 09:05:11 INFO - 'tabpaint']}, 09:05:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:05:11 INFO - 'ts_paint', 09:05:11 INFO - 'tpaint', 09:05:11 INFO - 'sessionrestore', 09:05:11 INFO - 'sessionrestore_no_auto_restore', 09:05:11 INFO - 'tabpaint']}, 09:05:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:05:11 INFO - 'tests': ['a11yr', 09:05:11 INFO - 'ts_paint', 09:05:11 INFO - 'tpaint', 09:05:11 INFO - 'sessionrestore', 09:05:11 INFO - 'sessionrestore_no_auto_restore', 09:05:11 INFO - 'tabpaint']}, 09:05:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:05:11 INFO - 'tests': ['a11yr', 09:05:11 INFO - 'ts_paint', 09:05:11 INFO - 'tpaint', 09:05:11 INFO - 'sessionrestore', 09:05:11 INFO - 'sessionrestore_no_auto_restore', 09:05:11 INFO - 'tabpaint']}, 09:05:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:05:11 INFO - 'tests': ['tsvgx', 09:05:11 INFO - 'tsvgr_opacity', 09:05:11 INFO - 'tart', 09:05:11 INFO - 'tscrollx', 09:05:11 INFO - 'cart']}, 09:05:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:05:11 INFO - 'tsvgr_opacity', 09:05:11 INFO - 'tart', 09:05:11 INFO - 'tscrollx', 09:05:11 INFO - 'cart']}, 09:05:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:11 INFO - 'talos_options': ['--disable-e10s'], 09:05:11 INFO - 'tests': ['tp5o']}, 09:05:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:11 INFO - 'tests': ['tp5o']}, 09:05:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:11 INFO - 'talos_options': ['--disable-e10s', 09:05:11 INFO - '--xperf_path', 09:05:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:05:11 INFO - 'tests': ['tp5n']}, 09:05:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:05:11 INFO - 'talos_options': ['--xperf_path', 09:05:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:05:11 INFO - 'tests': ['tp5n']}}, 09:05:11 INFO - 'talos.zip': {'path': '', 09:05:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:05:11 INFO - Running post-action listener: _resource_record_post_action 09:05:11 INFO - [mozharness: 2016-08-09 16:05:11.961097Z] Finished populate-webroot step (success) 09:05:11 INFO - [mozharness: 2016-08-09 16:05:11.961207Z] Running create-virtualenv step. 09:05:11 INFO - Running pre-action listener: _resource_record_pre_action 09:05:11 INFO - Running main action method: create_virtualenv 09:05:11 INFO - Creating virtualenv /builds/slave/test/build/venv 09:05:11 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:05:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:05:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:05:12 INFO - Using real prefix '/tools/python27' 09:05:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:05:12 INFO - Installing distribute.............................................................................................................................................................................................done. 09:05:15 INFO - Installing pip.................done. 09:05:15 INFO - Return code: 0 09:05:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:05:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e812a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61049c410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e776d30>, '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 0x10e4dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e76eb20>, '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.AWs7OVi6Yq/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.NDvhIEhgcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:05:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:05:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:05:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWs7OVi6Yq/Render', 09:05:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:15 INFO - 'HOME': '/Users/cltbld', 09:05:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:15 INFO - 'LOGNAME': 'cltbld', 09:05:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:15 INFO - 'MOZ_NO_REMOTE': '1', 09:05:15 INFO - 'NO_EM_RESTART': '1', 09:05:15 INFO - 'PAGER': '/bin/cat', 09:05:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:15 INFO - 'PWD': '/builds/slave/test', 09:05:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:15 INFO - 'SHELL': '/bin/bash', 09:05:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NDvhIEhgcs/Listeners', 09:05:15 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 09:05:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:15 INFO - 'USER': 'cltbld', 09:05:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:15 INFO - 'XPC_FLAGS': '0x0', 09:05:15 INFO - 'XPC_SERVICE_NAME': '0', 09:05:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:15 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:05:15 INFO - Cleaning up... 09:05:15 INFO - Return code: 0 09:05:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:05:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e812a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61049c410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e776d30>, '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 0x10e4dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e76eb20>, '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.AWs7OVi6Yq/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.NDvhIEhgcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:05:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:05:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:05:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWs7OVi6Yq/Render', 09:05:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:15 INFO - 'HOME': '/Users/cltbld', 09:05:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:15 INFO - 'LOGNAME': 'cltbld', 09:05:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:15 INFO - 'MOZ_NO_REMOTE': '1', 09:05:15 INFO - 'NO_EM_RESTART': '1', 09:05:15 INFO - 'PAGER': '/bin/cat', 09:05:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:15 INFO - 'PWD': '/builds/slave/test', 09:05:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:15 INFO - 'SHELL': '/bin/bash', 09:05:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NDvhIEhgcs/Listeners', 09:05:15 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 09:05:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:15 INFO - 'USER': 'cltbld', 09:05:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:15 INFO - 'XPC_FLAGS': '0x0', 09:05:15 INFO - 'XPC_SERVICE_NAME': '0', 09:05:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:16 INFO - Downloading/unpacking psutil>=3.1.1 09:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:05:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:05:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:05:19 INFO - Installing collected packages: psutil 09:05:19 INFO - Running setup.py install for psutil 09:05:19 INFO - building 'psutil._psutil_osx' extension 09:05:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:05:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:05:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:05:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:05:20 INFO - building 'psutil._psutil_posix' extension 09:05:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:05:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:05:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:05:20 INFO - ^ 09:05:20 INFO - 1 warning generated. 09:05:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:05:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:05:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:05:20 INFO - Successfully installed psutil 09:05:20 INFO - Cleaning up... 09:05:20 INFO - Return code: 0 09:05:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:20 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 0x10e812a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61049c410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e776d30>, '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 0x10e4dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e76eb20>, '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.AWs7OVi6Yq/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.NDvhIEhgcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:05:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:05:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:05:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWs7OVi6Yq/Render', 09:05:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:20 INFO - 'HOME': '/Users/cltbld', 09:05:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:20 INFO - 'LOGNAME': 'cltbld', 09:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:20 INFO - 'MOZ_NO_REMOTE': '1', 09:05:20 INFO - 'NO_EM_RESTART': '1', 09:05:20 INFO - 'PAGER': '/bin/cat', 09:05:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:20 INFO - 'PWD': '/builds/slave/test', 09:05:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:20 INFO - 'SHELL': '/bin/bash', 09:05:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NDvhIEhgcs/Listeners', 09:05:20 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 09:05:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:20 INFO - 'USER': 'cltbld', 09:05:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:20 INFO - 'XPC_FLAGS': '0x0', 09:05:20 INFO - 'XPC_SERVICE_NAME': '0', 09:05:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:05:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:05:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:05:23 INFO - Installing collected packages: mozsystemmonitor 09:05:23 INFO - Running setup.py install for mozsystemmonitor 09:05:23 INFO - Successfully installed mozsystemmonitor 09:05:23 INFO - Cleaning up... 09:05:23 INFO - Return code: 0 09:05:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:05:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e812a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61049c410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e776d30>, '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 0x10e4dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e76eb20>, '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.AWs7OVi6Yq/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.NDvhIEhgcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:05:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:05:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:05:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWs7OVi6Yq/Render', 09:05:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:24 INFO - 'HOME': '/Users/cltbld', 09:05:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:24 INFO - 'LOGNAME': 'cltbld', 09:05:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:24 INFO - 'MOZ_NO_REMOTE': '1', 09:05:24 INFO - 'NO_EM_RESTART': '1', 09:05:24 INFO - 'PAGER': '/bin/cat', 09:05:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:24 INFO - 'PWD': '/builds/slave/test', 09:05:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:24 INFO - 'SHELL': '/bin/bash', 09:05:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NDvhIEhgcs/Listeners', 09:05:24 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 09:05:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:24 INFO - 'USER': 'cltbld', 09:05:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:24 INFO - 'XPC_FLAGS': '0x0', 09:05:24 INFO - 'XPC_SERVICE_NAME': '0', 09:05:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:24 INFO - Downloading/unpacking jsonschema==2.5.1 09:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:05:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:27 INFO - Downloading functools32-3.2.3-2.tar.gz 09:05:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:05:27 INFO - warning: no files found matching '*.txt' 09:05:27 INFO - no previously-included directories found matching 'build' 09:05:27 INFO - no previously-included directories found matching 'dist' 09:05:27 INFO - no previously-included directories found matching '.git*' 09:05:27 INFO - Installing collected packages: jsonschema, functools32 09:05:27 INFO - Running setup.py install for functools32 09:05:27 INFO - warning: no files found matching '*.txt' 09:05:27 INFO - no previously-included directories found matching 'build' 09:05:27 INFO - no previously-included directories found matching 'dist' 09:05:27 INFO - no previously-included directories found matching '.git*' 09:05:27 INFO - Successfully installed jsonschema functools32 09:05:27 INFO - Cleaning up... 09:05:27 INFO - Return code: 0 09:05:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e812a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61049c410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e776d30>, '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 0x10e4dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e76eb20>, '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.AWs7OVi6Yq/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.NDvhIEhgcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:05:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:05:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:05:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWs7OVi6Yq/Render', 09:05:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:27 INFO - 'HOME': '/Users/cltbld', 09:05:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:27 INFO - 'LOGNAME': 'cltbld', 09:05:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:27 INFO - 'MOZ_NO_REMOTE': '1', 09:05:27 INFO - 'NO_EM_RESTART': '1', 09:05:27 INFO - 'PAGER': '/bin/cat', 09:05:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:27 INFO - 'PWD': '/builds/slave/test', 09:05:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:27 INFO - 'SHELL': '/bin/bash', 09:05:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NDvhIEhgcs/Listeners', 09:05:27 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 09:05:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:27 INFO - 'USER': 'cltbld', 09:05:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:27 INFO - 'XPC_FLAGS': '0x0', 09:05:27 INFO - 'XPC_SERVICE_NAME': '0', 09:05:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:05:27 INFO - Cleaning up... 09:05:27 INFO - Return code: 0 09:05:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:27 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 0x10e812a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61049c410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e776d30>, '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 0x10e4dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e76eb20>, '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.AWs7OVi6Yq/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.NDvhIEhgcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:05:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:05:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:05:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWs7OVi6Yq/Render', 09:05:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:27 INFO - 'HOME': '/Users/cltbld', 09:05:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:27 INFO - 'LOGNAME': 'cltbld', 09:05:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:27 INFO - 'MOZ_NO_REMOTE': '1', 09:05:27 INFO - 'NO_EM_RESTART': '1', 09:05:27 INFO - 'PAGER': '/bin/cat', 09:05:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:27 INFO - 'PWD': '/builds/slave/test', 09:05:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:27 INFO - 'SHELL': '/bin/bash', 09:05:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NDvhIEhgcs/Listeners', 09:05:27 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 09:05:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:27 INFO - 'USER': 'cltbld', 09:05:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:27 INFO - 'XPC_FLAGS': '0x0', 09:05:27 INFO - 'XPC_SERVICE_NAME': '0', 09:05:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:27 INFO - Downloading/unpacking blobuploader==1.2.4 09:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:30 INFO - Downloading blobuploader-1.2.4.tar.gz 09:05:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:05:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:05:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:05:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:05:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:05: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 09:05: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 09:05: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 09:05: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 09:05: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 09:05: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 09:05:31 INFO - Downloading docopt-0.6.1.tar.gz 09:05:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:05:31 INFO - Installing collected packages: blobuploader, requests, docopt 09:05:31 INFO - Running setup.py install for blobuploader 09:05:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:05:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:05:31 INFO - Running setup.py install for requests 09:05:32 INFO - Running setup.py install for docopt 09:05:32 INFO - Successfully installed blobuploader requests docopt 09:05:32 INFO - Cleaning up... 09:05:32 INFO - Return code: 0 09:05:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:32 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 0x10e812a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61049c410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e776d30>, '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 0x10e4dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e76eb20>, '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.AWs7OVi6Yq/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.NDvhIEhgcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:05:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:05:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:05:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWs7OVi6Yq/Render', 09:05:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:32 INFO - 'HOME': '/Users/cltbld', 09:05:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:32 INFO - 'LOGNAME': 'cltbld', 09:05:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:32 INFO - 'MOZ_NO_REMOTE': '1', 09:05:32 INFO - 'NO_EM_RESTART': '1', 09:05:32 INFO - 'PAGER': '/bin/cat', 09:05:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:32 INFO - 'PWD': '/builds/slave/test', 09:05:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:32 INFO - 'SHELL': '/bin/bash', 09:05:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NDvhIEhgcs/Listeners', 09:05:32 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 09:05:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:32 INFO - 'USER': 'cltbld', 09:05:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:32 INFO - 'XPC_FLAGS': '0x0', 09:05:32 INFO - 'XPC_SERVICE_NAME': '0', 09:05:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:05:32 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-B6AfJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:05:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:05:32 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-gwWOXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:05:33 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-TyvK1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:05:33 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-Fv6M6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:05:33 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-Z6XvgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:05:33 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-b67Vz6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:05:33 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-cw7a3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:05:33 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-GymI2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:05:33 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-pFe5LQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:05:33 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-zPt12u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:05:33 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-x2p6kb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:05:33 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-rYmxd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:05:34 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-uoGUyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:05:34 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-f4UN82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:05:34 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-A7f6Aa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:05:34 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-kIxDtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:05:34 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-SGykOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:05:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:05:34 INFO - Running setup.py install for manifestparser 09:05:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:05:34 INFO - Running setup.py install for mozcrash 09:05:34 INFO - Running setup.py install for mozdebug 09:05:35 INFO - Running setup.py install for mozdevice 09:05:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:05:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:05:35 INFO - Running setup.py install for mozfile 09:05:35 INFO - Running setup.py install for mozhttpd 09:05:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:05:35 INFO - Running setup.py install for mozinfo 09:05:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:05:35 INFO - Running setup.py install for mozInstall 09:05:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:05:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:05:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:05:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:05:35 INFO - Running setup.py install for mozleak 09:05:35 INFO - Running setup.py install for mozlog 09:05:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:05:36 INFO - Running setup.py install for moznetwork 09:05:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:05:36 INFO - Running setup.py install for mozprocess 09:05:36 INFO - Running setup.py install for mozprofile 09:05:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:05:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:05:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:05:36 INFO - Running setup.py install for mozrunner 09:05:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:05:36 INFO - Running setup.py install for mozscreenshot 09:05:36 INFO - Running setup.py install for moztest 09:05:37 INFO - Running setup.py install for mozversion 09:05:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:05:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:05:37 INFO - Cleaning up... 09:05:37 INFO - Return code: 0 09:05:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:05:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:37 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 0x10e812a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61049c410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e776d30>, '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 0x10e4dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e76eb20>, '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.AWs7OVi6Yq/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.NDvhIEhgcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:05:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:05:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:05:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWs7OVi6Yq/Render', 09:05:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:37 INFO - 'HOME': '/Users/cltbld', 09:05:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:37 INFO - 'LOGNAME': 'cltbld', 09:05:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:37 INFO - 'MOZ_NO_REMOTE': '1', 09:05:37 INFO - 'NO_EM_RESTART': '1', 09:05:37 INFO - 'PAGER': '/bin/cat', 09:05:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:37 INFO - 'PWD': '/builds/slave/test', 09:05:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:37 INFO - 'SHELL': '/bin/bash', 09:05:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NDvhIEhgcs/Listeners', 09:05:37 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 09:05:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:37 INFO - 'USER': 'cltbld', 09:05:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:37 INFO - 'XPC_FLAGS': '0x0', 09:05:37 INFO - 'XPC_SERVICE_NAME': '0', 09:05:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:05:37 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-1lGIXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:05:37 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:05:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:05:37 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-f4HqQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:05:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:05:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:05:37 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-mSUSuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:05:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:05:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:05:37 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-Fi35UL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:05:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:05:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:05:37 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-7klyz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:05:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:05:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:05:37 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-gr_FGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:05:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:05:38 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-H7mwhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:05:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:05:38 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-SjIla5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:05:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:05:38 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-kR0Mcw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:05:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:05:38 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-kuFRqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:05:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:05:38 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-SKDAeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:05:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:05:38 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-u3daeb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:05:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:05:38 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-tjh0fh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:05:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:05:38 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-slv8ZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:05:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:05:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:05:38 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-cFAGlG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:05:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:05:39 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-ic2Imm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:05:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:05:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:05:39 INFO - Running setup.py (path:/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/pip-dDQucB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:05:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:05:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:05:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:05:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:05:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:05:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:05:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:05: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 09:05: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 09:05: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 09:05: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 09:05: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 09:05: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 09:05:41 INFO - Downloading blessings-1.6.tar.gz 09:05:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:05:41 INFO - Installing collected packages: blessings 09:05:41 INFO - Running setup.py install for blessings 09:05:41 INFO - Successfully installed blessings 09:05:41 INFO - Cleaning up... 09:05:42 INFO - Return code: 0 09:05:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:05:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:05:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:05:42 INFO - Reading from file tmpfile_stdout 09:05:42 INFO - Current package versions: 09:05:42 INFO - blessings == 1.6 09:05:42 INFO - blobuploader == 1.2.4 09:05:42 INFO - docopt == 0.6.1 09:05:42 INFO - functools32 == 3.2.3-2 09:05:42 INFO - jsonschema == 2.5.1 09:05:42 INFO - manifestparser == 1.1 09:05:42 INFO - mozInstall == 1.12 09:05:42 INFO - mozcrash == 0.17 09:05:42 INFO - mozdebug == 0.1 09:05:42 INFO - mozdevice == 0.48 09:05:42 INFO - mozfile == 1.2 09:05:42 INFO - mozhttpd == 0.7 09:05:42 INFO - mozinfo == 0.9 09:05:42 INFO - mozleak == 0.1 09:05:42 INFO - mozlog == 3.3 09:05:42 INFO - moznetwork == 0.27 09:05:42 INFO - mozprocess == 0.23 09:05:42 INFO - mozprofile == 0.28 09:05:42 INFO - mozrunner == 6.12 09:05:42 INFO - mozscreenshot == 0.1 09:05:42 INFO - mozsystemmonitor == 0.3 09:05:42 INFO - moztest == 0.7 09:05:42 INFO - mozversion == 1.4 09:05:42 INFO - psutil == 3.1.1 09:05:42 INFO - requests == 1.2.3 09:05:42 INFO - wsgiref == 0.1.2 09:05:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:05:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:42 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 0x10e812a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61049c410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e776d30>, '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 0x10e4dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e76eb20>, '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.AWs7OVi6Yq/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.NDvhIEhgcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:05:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:05:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:05:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWs7OVi6Yq/Render', 09:05:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:42 INFO - 'HOME': '/Users/cltbld', 09:05:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:42 INFO - 'LOGNAME': 'cltbld', 09:05:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:42 INFO - 'MOZ_NO_REMOTE': '1', 09:05:42 INFO - 'NO_EM_RESTART': '1', 09:05:42 INFO - 'PAGER': '/bin/cat', 09:05:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:42 INFO - 'PWD': '/builds/slave/test', 09:05:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:42 INFO - 'SHELL': '/bin/bash', 09:05:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NDvhIEhgcs/Listeners', 09:05:42 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 09:05:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:42 INFO - 'USER': 'cltbld', 09:05:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:42 INFO - 'XPC_FLAGS': '0x0', 09:05:42 INFO - 'XPC_SERVICE_NAME': '0', 09:05:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:05:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:05:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:05:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:05:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:05:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:05:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:05:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:05:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:05:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:05:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:05:42 INFO - Cleaning up... 09:05:42 INFO - Return code: 0 09:05:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:05:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:05:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:05:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:05:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:05: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e812a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e8a7618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd61049c410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e776d30>, '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 0x10e4dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e76eb20>, '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.AWs7OVi6Yq/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.NDvhIEhgcs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:05: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', 'jsonschema'] in /builds/slave/test/build 09:05: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 jsonschema 09:05:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWs7OVi6Yq/Render', 09:05:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:05:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:05:42 INFO - 'HOME': '/Users/cltbld', 09:05:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:05:42 INFO - 'LOGNAME': 'cltbld', 09:05:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:05:42 INFO - 'MOZ_NO_REMOTE': '1', 09:05:42 INFO - 'NO_EM_RESTART': '1', 09:05:42 INFO - 'PAGER': '/bin/cat', 09:05:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:05:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:05:42 INFO - 'PWD': '/builds/slave/test', 09:05:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:05:42 INFO - 'SHELL': '/bin/bash', 09:05:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NDvhIEhgcs/Listeners', 09:05:42 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 09:05:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:05:42 INFO - 'USER': 'cltbld', 09:05:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:05:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:05:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:05:42 INFO - 'XPC_FLAGS': '0x0', 09:05:42 INFO - 'XPC_SERVICE_NAME': '0', 09:05:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:05:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:05:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:05:42 INFO - Cleaning up... 09:05:42 INFO - Return code: 0 09:05:42 INFO - Running post-action listener: _resource_record_post_action 09:05:42 INFO - Running post-action listener: _start_resource_monitoring 09:05:42 INFO - Starting resource monitoring. 09:05:42 INFO - [mozharness: 2016-08-09 16:05:42.892205Z] Finished create-virtualenv step (success) 09:05:42 INFO - [mozharness: 2016-08-09 16:05:42.893166Z] Running install step. 09:05:42 INFO - Running pre-action listener: _resource_record_pre_action 09:05:42 INFO - Running main action method: install 09:05:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:05:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:05:43 INFO - Reading from file tmpfile_stdout 09:05:43 INFO - Detecting whether we're running mozinstall >=1.0... 09:05:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:05:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:05:43 INFO - Reading from file tmpfile_stdout 09:05:43 INFO - Output received: 09:05:43 INFO - Usage: mozinstall [options] installer 09:05:43 INFO - Options: 09:05:43 INFO - -h, --help show this help message and exit 09:05:43 INFO - -d DEST, --destination=DEST 09:05:43 INFO - Directory to install application into. [default: 09:05:43 INFO - "/builds/slave/test"] 09:05:43 INFO - --app=APP Application being installed. [default: firefox] 09:05:43 INFO - mkdir: /builds/slave/test/build/application 09:05:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:05:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:06:17 INFO - Reading from file tmpfile_stdout 09:06:17 INFO - Output received: 09:06:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:06:17 INFO - Running post-action listener: _resource_record_post_action 09:06:17 INFO - [mozharness: 2016-08-09 16:06:17.521876Z] Finished install step (success) 09:06:17 INFO - [mozharness: 2016-08-09 16:06:17.521999Z] Running run-tests step. 09:06:17 INFO - Running pre-action listener: _resource_record_pre_action 09:06:17 INFO - Running main action method: run_tests 09:06:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:06:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:06:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:06:17 INFO - 2016-08-09 09:06:17.535 screenresolution[1674:7732] starting screenresolution argv=screenresolution get 09:06:17 INFO - 2016-08-09 09:06:17.538 screenresolution[1674:7732] Display 0: 1600x1200x32@60 09:06:17 INFO - 2016-08-09 09:06:17.544 screenresolution[1675:7734] starting screenresolution argv=screenresolution list 09:06:17 INFO - Available Modes on Display 0 09:06:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:06:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:06:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:06:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:06:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:06:17 INFO - Intel Iris: 09:06:17 INFO - Chipset Model: Intel Iris 09:06:17 INFO - Type: GPU 09:06:17 INFO - Bus: Built-In 09:06:17 INFO - VRAM (Dynamic, Max): 1536 MB 09:06:17 INFO - Vendor: Intel (0x8086) 09:06:17 INFO - Device ID: 0x0a2e 09:06:17 INFO - Revision ID: 0x0009 09:06:17 INFO - Displays: 09:06:17 INFO - Display: 09:06:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:06:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:06:17 INFO - Display Serial Number: 1600x1200 60 09:06:17 INFO - Main Display: Yes 09:06:17 INFO - Mirror: Off 09:06:17 INFO - Online: Yes 09:06:17 INFO - Rotation: Supported 09:06:17 INFO - Return code: 0 09:06:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:06:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:06:17 INFO - Python 2.7.3 09:06:17 INFO - Return code: 0 09:06:17 INFO - grabbing minidump binary from tooltool 09:06:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:17 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 0x10e776d30>, '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 0x10e4dbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e76eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:06:17 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:06:17 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:06:17 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:06:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:06:17 INFO - Return code: 0 09:06:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:06:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:06:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:06:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:06:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:06:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0239', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:06:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0239 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:06:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AWs7OVi6Yq/Render', 09:06:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:17 INFO - 'HOME': '/Users/cltbld', 09:06:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:17 INFO - 'LOGNAME': 'cltbld', 09:06:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:06:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:06:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:17 INFO - 'MOZ_NO_REMOTE': '1', 09:06:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:06:17 INFO - 'NO_EM_RESTART': '1', 09:06:17 INFO - 'PAGER': '/bin/cat', 09:06:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:17 INFO - 'PWD': '/builds/slave/test', 09:06:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:06:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:17 INFO - 'SHELL': '/bin/bash', 09:06:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NDvhIEhgcs/Listeners', 09:06:17 INFO - 'TMPDIR': '/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/', 09:06:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:17 INFO - 'USER': 'cltbld', 09:06:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:17 INFO - 'XPC_FLAGS': '0x0', 09:06:17 INFO - 'XPC_SERVICE_NAME': '0', 09:06:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0239', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:06:18 INFO - mozversion application_buildid: 20160809064419 09:06:18 INFO - mozversion application_changeset: 0813514a828bf931605bd22596d8fe876c1bb368 09:06:18 INFO - mozversion application_display_name: Nightly 09:06:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:06:18 INFO - mozversion application_name: Firefox 09:06:18 INFO - mozversion application_remotingname: firefox 09:06:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:06:18 INFO - mozversion application_vendor: Mozilla 09:06:18 INFO - mozversion application_version: 51.0a1 09:06:18 INFO - mozversion platform_buildid: 20160809064419 09:06:18 INFO - mozversion platform_changeset: 0813514a828bf931605bd22596d8fe876c1bb368 09:06:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:06:18 INFO - mozversion platform_version: 51.0a1 09:06:18 INFO - using testdate: 1470758778 09:06:18 INFO - actual date: 1470758778 09:06:18 INFO - starting webserver on 'localhost:49271' 09:06:18 INFO - SUITE-START | Running 2 tests 09:06:18 INFO - TEST-START | dromaeo_css 09:06:18 INFO - operating with platform_type : mac_ 09:06:18 INFO - Initialising browser for dromaeo_css test... 09:06:18 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpm4f61z/profile http://localhost:49271/getInfo.html) 09:06:20 INFO - PROCESS | 1685 | __metrics Screen width/height:1600/1200 09:06:20 INFO - PROCESS | 1685 | colorDepth:24 09:06:20 INFO - PROCESS | 1685 | Browser inner width/height: 1024/689 09:06:20 INFO - PROCESS | 1685 | __metrics 09:06:20 INFO - PROCESS | 1685 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:06:20 INFO - PROCESS | 1685 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:06:21 INFO - TEST-INFO | 1685: exit 0 09:06:21 INFO - Browser initialized. 09:06:21 INFO - Running cycle 1/1 for dromaeo_css test... 09:06:21 INFO - TEST-INFO | started process 1688 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmpm4f61z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:06:33 INFO - PROCESS | 1688 | RSS: Main: 243687424 09:06:33 INFO - PROCESS | 1688 | 09:07:25 INFO - PROCESS | 1688 | 1470758845315 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:09:37 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 09:09:39 INFO - PROCESS | 1688 | RSS: Main: 382812160 09:09:39 INFO - PROCESS | 1688 | 09:12:41 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 09:12:42 INFO - PROCESS | 1688 | RSS: Main: 504610816 09:12:42 INFO - PROCESS | 1688 | 09:15:44 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 09:15:45 INFO - PROCESS | 1688 | RSS: Main: 362647552 09:15:45 INFO - PROCESS | 1688 | 09:18:47 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 09:18:48 INFO - PROCESS | 1688 | RSS: Main: 373989376 09:18:48 INFO - PROCESS | 1688 | 09:21:51 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 09:21:52 INFO - PROCESS | 1688 | RSS: Main: 348557312 09:21:52 INFO - PROCESS | 1688 | 09:24:55 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 09:24:55 INFO - PROCESS | 1688 | RSS: Main: 476856320 09:24:55 INFO - PROCESS | 1688 | 09:24:55 INFO - PROCESS | 1688 | __start_tp_report 09:24:55 INFO - PROCESS | 1688 | _x_x_mozilla_page_load 09:24:55 INFO - PROCESS | 1688 | _x_x_mozilla_page_load_details 09:24:55 INFO - PROCESS | 1688 | |i|pagename|runs| 09:24:55 INFO - PROCESS | 1688 | |0;dojo.html;3424.5034470001847;3425.554677891874;3434.9656503434976;3441.5181874537584;3491.8952431427065;5764.365919748824;5882.499987501057;5912.674802885845;5918.082697181938;5972.372900845408;5741.368449470555;5814.040683287263;5920.674362910038;6057.242844644419;6081.966065768819;5140.948590514085;5173.922391164136;5202.297689811865;5223.320968274119;5437.755301011444;5386.48828361305;5424.186372044185;5431.293931788881;5457.263269458632;5477.7261136943;5275.86810329741;5281.339832520935;5347.358317001946;5368.62419630626;5371.221172929903;5318.202269659543;5319.9202011969855;5339.652922560022;5376.865578360533;5408.4591540845995;5285.973570132163;5324.733763311858;5332.30680011596;5366.731663416814;5395.082835917903;5757.568182386296;5794.971025144847;6023.036314189752;6030.396960303979;6061.151438798572;6281.466075383598;6312.684365781693;6337.271213810379;6343.524235682342;6347.968260158716;6314.147590075327;6315.0843127746675;6338.904916426257;6349.936500635027;6357.904631430532;5850.795222167204;5894.67579283135;6060.151578778975;6090.056041313604;6108.877822443526;6270.2789179244055;6314.557980941382;6318.336574659686;6323.588966717149;6326.367363263636;6211.068339749074;6238.906416403757;6277.2781130170615;6287.1512345832725;6354.142190804275;6284.371562843679;6308.747650093945;6330.177076980055;6335.968320158462;6352.968235158794;6315.431611155018;6323.08315294289;6327.96836015817;6328.303886572473;6331.303556608769;6246.250449946036;6268.686565671698;6294.339094395023;6314.116023756677;6318.936810631927;5058.266551350033;5063.721495317719;5066.923996140061;5073.847784566469;5091.643584949092;4542.886427839265;4545.795439205178;4557.977210113928;4580.290055041474;4581.770911454414;5773.191753154561;5836.737346819404;5853.356130825606;5884.176215329857;5913.142594323799;6314.4317011468265;6321.336259692666;6322.304546499881;6326.2092238470195;6331.271903731038;5888.823335299948;5898.557608179318;5904.498117659961;5911.261301673705;5955.493783028404;5483.835484935458;5531.363893152258;5575.637583557056;5586.413426590231;5598.468145526169;6280.685965701788;6296.5277604178955;6325.4307112358265;6349.333320001518;6392.041193820778;6221.191245138103;6276.811695649138;6283.2460104787715;6291.339409362135;6297.685115744102;6211.565190436626;6378.649174295459;6385.265694445013;6403.967980160256;6419.614823110628;6264.49884009269;6314.210723659543;6342.270638876404;6355.713992870239;6362.045693146065;6312.747490100344;6321.241451025906;6325.462335701332;6333.90499142504;6352.809415717536;6111.98679349419;6318.462930650762;6326.9683651581445;6346.048092786119;6346.07981842621;4788.922492439173;5793.739281732416;6184.319724830175;6282.780102696383;6316.273628532594;6164;6165.414285642857;6169.105479705508;6171.889059969249;6179.721912514037;6099.461111717566;6177.444030037318;6184.721687523982;6212.347703491249;6311.810645680637;6122.051082082285;6136.151476879544;6158;6195.783147589813;6219.689015549114 09:24:55 INFO - PROCESS | 1688 | |1;ext.html;443.5675216663752;512.8230760387667;525.2515165888606;532.4622131647038;554.1217170784307;31593.526097108563;36413.45379819301;41047.973800654996;42506.787466062626;42565.78717106418;44410.333844992354;44659.55340446594;44677.7766111169;44735.77632111835;44997;9633.566489507954;9816.803663926717;9818.9509052455;9823.428251479765;9828.017198280186;8775.912240877573;8974.14745599167;8978.685745998891;9131.2238459731;9133.17801397874;11624.360660163687;11843.348615826126;11854.229475084136;12069.758604827899;12158.148929574932;11870.703232419215;11976.700582485464;11987.520499180022;11990.880091199064;11992.820107698391;11747.588834390755;11829.585964491287;11834.526618935231;11834.822477662841;11843.348615826126;41288.38067428961;41585;41627.167456651;41628.1674366511;41781.79109104465;3392.7794693344854;3425.6745609167087;3572.8570857165682;3622.4023036198996;3862.903427414309;31583.52624710631;31927.042188734376;33204.00387988364;33245.50131748026;33259;3210.7752457327997;3289.3585750778607;3335.8999230023137;3361.0757041813454;3395.4567269236804;374921;380281;380982;382995;388402;60650.78698426007;60863.39136608577;60888;60966;61139;55285.72357138189;63076;64776.67611661911;64874.67562662157;65029.67485162544;16368.181590920407;16533.090680012476;16544;16561.585960350847;16569.83430165707;12862.228266304051;12934.741305173844;12948.417321220568;12961.02792290563;13002.024848136427;12191.878081219074;14794.964352495223;15210.771838422432;16871.83128168727;17056.48830535086;6113.349395663235;6307.148534947815;6325.177726895474;6385.45723613498;6394.360563943662;895.2166854002747;959.8521469984759;965.9082387173207;989.0999190736395;995.0248756218801;30194.69805301919;30208.244793880327;30583.847080764455;30994.69005309963;30998;1077.7844431113808;1089.6131873184904;1096.7916095941746;1127.5997021057406;1142.605800998654;14171.574852754435;15516.534503964898;15687.607809804618;16144.515664530083;16208.78434117446;663597;664284;664903;665828;666529;53190;59936.70031649901;60558;60823.69588152119;61198.38801612016;1371.3965855023757;1398.1960372785688;1419.8438171800888;1420.2969530082676;1424.430227908824;26820;30952.53571196479;31587.36825263528;32246.19384515312;32497.512537311486;53053.46946530485;53451.93096138133;53793.73103134616;53843.46156538385;53870.730646346514;623802;674435;682294;686044;885703;26301.868490657424;26558.7344126564;26578.867105665122;26594.069207577642;26680.8665956669;45941.540584595066;46101.76949115233;46415.76792116018;47050;47472.52527474681;34957.47563786495;37773.81113094417;38325;38677.2264554713;39543.80228098841;38168;38411;38692.80653596714;39614.60385396109;40075;37101.814490927376;37150;37240;37274.813625931696;38302.42546361751;30072;33774.49338259977;34948.126296841765;36086;36101.81949090238;25686.614700779875;25693;25734.742652573233;26019;26443.091259961773 09:24:55 INFO - PROCESS | 1688 | |2;jquery.html;307.19974466514714;372.60503865902143;376.1968197897489;380.42365815789077;405.28062688727505;14777.778333324995;15316;20446.795532044674;20541;21021.474463138427;19370.22519099238;19958.90020549895;20324.59350812983;20553.486162845937;21298;5267.55225805807;5820.53435725142;6062;6067.271927368722;6072.362401947798;5887;6069.878602427949;6076.665783381913;6085.2393450818645;6085.726142323606;5241.689577605599;5622.971885140569;5910;6033.155358249848;6055.969720151393;5946.910796338058;6002.3697511761075;6004.309504407005;6004.909926351108;6010.068439391901;5021;5101.515356041207;5189.974050129763;5207.765650545697;5228.47715228478;25882;25979.480410391687;26047;26160.34599135018;26380.86809565959;2039.4493486758493;2430.0765709030634;2631.6052592111146;3012.3824615953895;3159.4786860168047;12214.816777748341;13419.926405887505;16266.186690665538;16375.754363684555;16409.507714768577;1795.6767781799401;1852.536865783554;1873.915673794669;1877.3241633012105;1951.4438385060332;116591;125562;126397.36801315934;132609.3369533156;135109;11520;11558.653240402802;11760.176787624785;11766.52933882635;11912.583059592873;14845.183514906785;14855.851441485664;14857.9599428039;14868;14868.330925108396;6992.440604751608;8441.620127094173;8611.181937715906;8706.695265665672;8716.38985271025;8591.699290524926;8597.226249637562;8741.606627701769;8774.990876049336;8781.429207101519;3333.3999880021343;4517.186906356887;4597.3333866589155;4683.297505374221;4687.648426368036;1855.7030875060195;1932.21745193197;1936.8353689936232;1961.3429501117;1985.622731680976;776.704852156177;779.2324560308092;790.6442101054548;850.3664769746455;965.6764983730388;16404.26180821842;17528.737068943978;20830.479238018866;20886.373408797455;20890.686639700416;906.3202598051462;907.5144797533288;916.5463095767504;940.7695114696943;1032.6179313653995;9502.572384242587;10584.57661693539;10644.520996555173;11301.378424186585;11344.602938897098;592251;596919;602184;609494;611509;75428.62285688537;76449;107493.92506074838;131236;137360;954.050719534082;967.3615413826841;990.7869807991317;995.2485873165714;996.1720243353527;18905.527361816065;19127.043647817274;19139.712904306725;19146;19208.327708530134;53470.66323341947;54454;54788.97321294575;55529.72235138799;55799.32602021946;77886.05284867923;79616.20383796089;79630.20369796228;81231.59384203042;82641.58679206566;6984.755533556301;7187.460940429384;7403.370713489414;7661.118971318369;8247.752567422987;10251.641192558225;11886.465109069939;13226.338683066;13610.59168224955;13631.522896698965;11499.54001839917;11931.940340298243;11937.940310298392;11943.223690460092;12006.339651319262;9012.504312262627;11262.887371126184;11494.885051149382;11709.531618735497;11969.521219151136;10744.73138171552;11504.137189710638;12054.819177712518;12075;12078.939605301917;7433.776986690408;7564.924350756422;7588.392928565812;7655.272749088827;7658.923410765821;9438.52807359643;11667.124965627441;11960.76078478408;12002.519899204284;12056.879431205576 09:24:55 INFO - PROCESS | 1688 | |3;mootools.html;1370.1025828082604;1506.7965824613677;1682.621410182709;1883.585611165543;1903.1816318982833;8223.958880205599;8721.084286149959;8730.956345218265;8742.169493898085;8743.081976392483;7708.96145519273;7945.880811787828;8323.91676083239;8537.74386768398;8540.70107546236;2267.455810605455;2306.9653955190684;2311.0062673050584;2311.4799170186743;2315.606346921019;2260.8530445520987;2278.28234106256;2304.873231972241;2306.9653955190684;2308.572914010905;1899.5346140195672;2271.7273927128686;2282.7603101674335;2301.666258392532;2301.9986305956963;2262.0386335807216;2276.1350686739015;2280.076568989562;2280.270313499681;2281.8060464860423;2251.6960210371553;2255.188132272397;2256.6163752162006;2257.6500642400456;2261.694671219381;8571.400001999862;8659.48043117415;8671.56642167889;8701.260392866614;8704.738857834274;5130.384353877558;5308.362996440452;5315.123004704219;5320.494553017457;5332.440093790135;3384.3062172254877;3443.1220038890174;3598.4962105305276;3598.7660802047785;3601.0637234319265;1073.431081526792;1073.5222825842504;1076.9730756731144;1098.758273179899;1100.8073587122221;11263.098952083814;11410.885891141148;11419.143564232632;11430.456888320137;11478.770424591461;3628.673419392215;4546.52261512543;4728.1252968201;4751.95248047522;4755.738434386073;9745.610175592898;9762.951185244028;9772.609095636237;9776.804463910681;9795.951020244853;4716.693414928019;5051.924221136685;5302.840914772563;5312.335958005249;5319.308489896289;4354.847580334673;4508.9098218036115;4727.905441891143;4847.878803029953;4863.148948933993;3844.96155038446;3876.7092468064443;3978.701597380208;4005.2790497710107;4018.1160144768105;1280.3406245783242;1285.7685616588917;1288.452407726731;1300.9544665936837;1305.7584346895856;673.6665350651459;932.7234629225079;935.3592788939462;946.602426980656;946.6118891254553;5432.483914028161;5646.943530564724;5995.096280349286;6035.456808887134;6101.298350689639;433.62924699382205;436.6266841850236;446.8726412972287;448.83617479619704;452.42767898608287;3950.3876899080688;4066.8169932353117;4070.8778736637946;4205.747655140702;4228.323468245066;10370.429626370622;10511.421871796972;10642.988916052962;10653.201009924287;10662.626808061681;10105.898941010642;10136.087752102347;10423.166146708245;10427.478626068818;10441.895581044093;7874.606269686493;7935.801604959922;8086.757397278091;8095.745159500169;8105.959470202612;5925.674087925206;5950.256217972754;6020.668863212565;6032.90950635732;6104.206453161239;9702.223822094074;9710.271729620448;9897.703068907944;9928.007199280302;9928.404295742279;9534.951155372764;10032.398056116657;10126.493675316058;10127.64553240633;10167;6935.167779866449;7122.109736282965;7142.0001199833105;7456.701731930662;7617.542947423172;7784.9610751945875;7882.487638303492;8041.839163216586;8287.50274983503;8315.459495132796;6059.939400606114;6315.86314463401;7231.674574644258;7420.888686669812;7567.167611562613;7306.6711997959155;7329.340359367682;7354.485186036926;7418.703251869865;7486.288802563748;6097.932861749087;6813.2846051163915;6871.896921546281;6921.065656136358;7025.367716905503;4941.925871111865;4952.579030782424;5023.723695196799;5119.97600479898;5121.743912804271;4895.069936712013;4937.629677774109;5035.723035233097;5110.821121260738;5149.356330458692 09:24:55 INFO - PROCESS | 1688 | |4;prototype.html;289.26238092863196;301.2949697707365;311.7552721113925;315.8736505397842;319.4170638584584;4429.832669641206;5055.949440505589;5272.103742363798;5409.296791417115;5489.39616642169;4929.605631549476;5228.4771522847695;5354.437784032679;5461.808836690717;5482.643628164166;3396.7282617390674;3649.5073165122767;4194;4197.307444271692;4276.412770273986;3643.0528062703756;3958.010497375656;4207.600277973587;4232.365145228209;4238.88055972014;3146.244901223701;3803.3344164771192;4044.939325910114;4154.792260386984;4193.245215861144;3467.11042481848;4023.879283621496;4027.436158937751;4060.451839001741;4116.547179810218;3105.5186446100893;3372.9325413491592;3415.4364529852546;3520.3135388599235;3546.929061418783;3910.198409326081;4781.139394908915;4989.975050124763;5188.351456067991;5392.946070539323;4136.910860015703;4477.507474177838;4694.342792009122;4761.952380476186;4776.617870570346;4750.691205071659;5250.894982100336;5297.920531192035;5745.885082298372;5788.292453726161;3320.286138480238;3905.9804700976315;3934.940975885364;3935.1145992151824;3972.682185425159;5629.155626655994;5630.915536266959;5844.73698683556;5994.940050599525;6053.727582258765;3844.2888065707934;3949.0917089069676;3992.2813893499447;4028.576999415078;4060.2285565742413;3825.3496905526126;4046.47395838545;4061.329880569732;4067.349224124126;4068.3083875741195;3899.512560929884;4294.291441912112;4404.4714634243455;4563.794629241692;4702.553257440538;5065.189569668835;5111.948880511221;5388.46115388458;5714.828555143353;5806.68063256525;1336.1648969394128;1362.5503583817317;1378.0078343592597;1380.7997840313099;1390.9652258693613;769.8183288651893;831.9001719793664;837.8491871463195;842.7640260727006;845.7335939179139;3682.576503702055;4229.555896630871;4392.407025051577;4399.758013309235;4405.603495685404;4170.769622961173;4671.719696818134;4820.325154478376;4827.927581086286;4940.407151141886;3967.056142490159;4246.299360605465;4530.433695788027;4536.8638940831825;4553.612942899825;2362.3975886148924;2379.5121999990306;2403.579373609611;2429.1740808125323;2542.872856357175;4947.381577302837;4968.627352948543;4971.279164521125;4980.576650984634;4999.700017998932;4180;4488.753118578445;4686.179918514246;4917.532834380728;4988.700677959334;4224.725392849455;4608.479201863377;4959.380077490314;4989.401271847401;5094.821681241138;4620.5148459411275;4874.7562621868055;4875.1712208923855;4882.511748825231;4893.755312234302;4262.701610887332;4507.842225522091;4609.810340088431;4846.781894814812;4892.36105904526;4795.448523419712;4835.758212089452;4946.752662366939;4977.576905963034;5086.54221120101;3370.1574606348413;3382.6955573998457;3386.5428167197115;3390.593128824557;3451.2752322012657;4809.735464549483;4814.435308524813;4862.353235073695;4915.336429582104;4947.77735001933;4085.4484644572603;4745.928811067768;5152.5877929766275;5730.656160630376;5751.511121554715;5177;5471.671699698031;5707.971460142673;5776.718196312517;5784.2769653793275;5676.17695434168;5708.373113662587;5712.742926568231;5729.713514324184;5742.885142297214;4616.584507394216;5358.873667276098;5569.913866795936;5851.4066457830395;5856.531477481706;4504.662150338803;4914.837471512523;5032.2890217326285;5759.709016782524;5766.654000759968 09:24:55 INFO - PROCESS | 1688 | |5;yui.html;709.7267551992483;718.2350796401831;763.5723994563042;794.6900708723603;837.2715737308542;15137;16469.50591482256;16500.669986600275;16505.83494165058;16506.66986660269;14764.6308842279;14842.925785371084;14843.925780371083;14866.47967321144;14881.776773348409;712.8217945513621;722.5953466058997;865.3942240431708;874.2961915657893;888.0098689960712;868.2272777228272;886.7916039730658;889.724185502493;890.2121622364222;890.3678388344251;948.7248697877607;949.6581230756922;949.738821823997;951.219999600328;953.6042542344918;751.6767789850362;916.1891725822695;946.3044662173297;947.7725345916965;952.0764858087679;706.9540479868792;708.0936401406157;708.2138825903244;709.6664567653195;715.8389362393472;14877.404903803836;14889.255537223096;14921.776173357408;14922.552323430205;14931.2534373282;13079.542216022393;13084.644763942486;14098.436062557485;14106.365213565414;14502.202378869159;12933.094683372168;13174.143680660822;13215.339233038405;13322;13726.65683357914;4541.321554384054;4598.149342371672;4627.514111018362;4717.268823332389;4764.523547645243;124504.64233751775;126658;127472;127699;127763.72236277611;11012.559497620166;11178.44107794607;11229.38238396896;11309.604163854226;11755.294682319089;22747;22773.430664233525;22776.544469110857;22783.772162278496;22813.31560053201;8059.879101813477;8102.108768035511;8121.4721043132;8147.67409303633;8271.586420678943;3985.282649123186;4454.732716037048;4458.4426946631675;4527.660425468103;4612.238980568236;3521.3837578423672;3622.8550857965915;3624.8912532624063;3632.9818350908076;3640.890773276806;539.8596364945064;651.133991790917;658.0819756439739;658.5225711359226;659.446065305146;586.2788769813154;614.5452365249772;625.586487331878;626.3204423200787;652.7030201258416;9060.682876099305;9221.17009469151;9237.584308706124;9262.44425334482;9397.059484735388;542.9131338985743;561.6994907724348;575.529522071104;621.6798348850346;644.7711062572813;3960.6039396060164;4374.978125109417;4389.670774691911;4644.373009643722;4733.053389322081;94364.0563594369;140851;143770.5622943778;161627;165003;86864.56567717121;122489;135056;137958;138364;116680;118298.04254893697;118869;119873.60252794895;128529.07206391911;8728.869066963875;9600.519974001132;9890.307678462726;10065.345752526062;10086.596536138471;52558.47441525536;66290.66854665696;66417.66791166013;67204.3279567198;67350.24446821882;84570.15429845623;86821.69767453367;89105.55447222722;101666.98333016575;105548.94451055699;12792.168509046882;13500.662483437993;13599.320033998458;13779.242141681903;13979.790303145257;14840.18378989166;15222.543323700307;15250.694986100438;15353.539393818204;15766.9211653941;13984.300784960915;14024.719505610035;14217.431302748188;14225.644358890697;14604.12375257488;13712.0401571893;13750.65623359424;14057.437702491785;14110.36503357331;14126.646833829236;13523.188608683116;13728.588142355746;13729.862701372858;13773.655658608615;13775.931120344334;9457.810843783223;9873.901260987299;11227.326360418401;11555.595554155565;12044.75910481803;11326.716832078935;11442.586000605796;11554.364509952033;11709.707257318636;11715.882841171479 09:24:55 INFO - PROCESS | 1688 | __end_tp_report 09:24:55 INFO - PROCESS | 1688 | __start_cc_report 09:24:55 INFO - PROCESS | 1688 | _x_x_mozilla_cycle_collect,1423 09:24:55 INFO - PROCESS | 1688 | __end_cc_report 09:24:55 INFO - PROCESS | 1688 | __startTimestamp1470759895597__endTimestamp 09:24:55 INFO - PROCESS | 1688 | 09:24:55 INFO - PROCESS | 1688 | ------- Summary: start ------- 09:24:55 INFO - PROCESS | 1688 | Number of tests: 6 09:24:55 INFO - PROCESS | 1688 | 09:24:55 INFO - PROCESS | 1688 | [#0] dojo.html Cycles:165 Average:5868.36 Median:6164.00 stddev:636.58 (10.3%) stddev-sans-first:608.98 09:24:55 INFO - PROCESS | 1688 | Values: 3424.5 3425.6 3435.0 3441.5 3491.9 5764.4 5882.5 5912.7 5918.1 5972.4 5741.4 5814.0 5920.7 6057.2 6082.0 5140.9 5173.9 5202.3 5223.3 5437.8 5386.5 5424.2 5431.3 5457.3 5477.7 5275.9 5281.3 5347.4 5368.6 5371.2 5318.2 5319.9 5339.7 5376.9 5408.5 5286.0 5324.7 5332.3 5366.7 5395.1 5757.6 5795.0 6023.0 6030.4 6061.2 6281.5 6312.7 6337.3 6343.5 6348.0 6314.1 6315.1 6338.9 6349.9 6357.9 5850.8 5894.7 6060.2 6090.1 6108.9 6270.3 6314.6 6318.3 6323.6 6326.4 6211.1 6238.9 6277.3 6287.2 6354.1 6284.4 6308.7 6330.2 6336.0 6353.0 6315.4 6323.1 6328.0 6328.3 6331.3 6246.3 6268.7 6294.3 6314.1 6318.9 5058.3 5063.7 5066.9 5073.8 5091.6 4542.9 4545.8 4558.0 4580.3 4581.8 5773.2 5836.7 5853.4 5884.2 5913.1 6314.4 6321.3 6322.3 6326.2 6331.3 5888.8 5898.6 5904.5 5911.3 5955.5 5483.8 5531.4 5575.6 5586.4 5598.5 6280.7 6296.5 6325.4 6349.3 6392.0 6221.2 6276.8 6283.2 6291.3 6297.7 6211.6 6378.6 6385.3 6404.0 6419.6 6264.5 6314.2 6342.3 6355.7 6362.0 6312.7 6321.2 6325.5 6333.9 6352.8 6112.0 6318.5 6327.0 6346.0 6346.1 4788.9 5793.7 6184.3 6282.8 6316.3 6164.0 6165.4 6169.1 6171.9 6179.7 6099.5 6177.4 6184.7 6212.3 6311.8 6122.1 6136.2 6158.0 6195.8 6219.7 09:24:55 INFO - PROCESS | 1688 | 09:24:55 INFO - PROCESS | 1688 | [#1] ext.html Cycles:180 Average:71995.01 Median:206097.91 stddev:162647.85 (78.9%) stddev-sans-first:163015.40 09:24:55 INFO - PROCESS | 1688 | Values: 443.6 512.8 525.3 532.5 554.1 31593.5 36413.5 41048.0 42506.8 42565.8 44410.3 44659.6 44677.8 44735.8 44997.0 9633.6 9816.8 9819.0 9823.4 9828.0 8775.9 8974.1 8978.7 9131.2 9133.2 11624.4 11843.3 11854.2 12069.8 12158.1 11870.7 11976.7 11987.5 11990.9 11992.8 11747.6 11829.6 11834.5 11834.8 11843.3 41288.4 41585.0 41627.2 41628.2 41781.8 3392.8 3425.7 3572.9 3622.4 3862.9 31583.5 31927.0 33204.0 33245.5 33259.0 3210.8 3289.4 3335.9 3361.1 3395.5 374921.0 380281.0 380982.0 382995.0 388402.0 60650.8 60863.4 60888.0 60966.0 61139.0 55285.7 63076.0 64776.7 64874.7 65029.7 16368.2 16533.1 16544.0 16561.6 16569.8 12862.2 12934.7 12948.4 12961.0 13002.0 12191.9 14795.0 15210.8 16871.8 17056.5 6113.3 6307.1 6325.2 6385.5 6394.4 895.2 959.9 965.9 989.1 995.0 30194.7 30208.2 30583.8 30994.7 30998.0 1077.8 1089.6 1096.8 1127.6 1142.6 14171.6 15516.5 15687.6 16144.5 16208.8 663597.0 664284.0 664903.0 665828.0 666529.0 53190.0 59936.7 60558.0 60823.7 61198.4 1371.4 1398.2 1419.8 1420.3 1424.4 26820.0 30952.5 31587.4 32246.2 32497.5 53053.5 53451.9 53793.7 53843.5 53870.7 623802.0 674435.0 682294.0 686044.0 885703.0 26301.9 26558.7 26578.9 26594.1 26680.9 45941.5 46101.8 46415.8 47050.0 47472.5 34957.5 37773.8 38325.0 38677.2 39543.8 38168.0 38411.0 38692.8 39614.6 40075.0 37101.8 37150.0 37240.0 37274.8 38302.4 30072.0 33774.5 34948.1 36086.0 36101.8 25686.6 25693.0 25734.7 26019.0 26443.1 09:24:55 INFO - PROCESS | 1688 | 09:24:55 INFO - PROCESS | 1688 | [#2] jquery.html Cycles:180 Average:34976.36 Median:1733.34 stddev:100352.62 (5789.6%) stddev-sans-first:100600.37 09:24:55 INFO - PROCESS | 1688 | Values: 307.2 372.6 376.2 380.4 405.3 14777.8 15316.0 20446.8 20541.0 21021.5 19370.2 19958.9 20324.6 20553.5 21298.0 5267.6 5820.5 6062.0 6067.3 6072.4 5887.0 6069.9 6076.7 6085.2 6085.7 5241.7 5623.0 5910.0 6033.2 6056.0 5946.9 6002.4 6004.3 6004.9 6010.1 5021.0 5101.5 5190.0 5207.8 5228.5 25882.0 25979.5 26047.0 26160.3 26380.9 2039.4 2430.1 2631.6 3012.4 3159.5 12214.8 13419.9 16266.2 16375.8 16409.5 1795.7 1852.5 1873.9 1877.3 1951.4 116591.0 125562.0 126397.4 132609.3 135109.0 11520.0 11558.7 11760.2 11766.5 11912.6 14845.2 14855.9 14858.0 14868.0 14868.3 6992.4 8441.6 8611.2 8706.7 8716.4 8591.7 8597.2 8741.6 8775.0 8781.4 3333.4 4517.2 4597.3 4683.3 4687.6 1855.7 1932.2 1936.8 1961.3 1985.6 776.7 779.2 790.6 850.4 965.7 16404.3 17528.7 20830.5 20886.4 20890.7 906.3 907.5 916.5 940.8 1032.6 9502.6 10584.6 10644.5 11301.4 11344.6 592251.0 596919.0 602184.0 609494.0 611509.0 75428.6 76449.0 107493.9 131236.0 137360.0 954.1 967.4 990.8 995.2 996.2 18905.5 19127.0 19139.7 19146.0 19208.3 53470.7 54454.0 54789.0 55529.7 55799.3 77886.1 79616.2 79630.2 81231.6 82641.6 6984.8 7187.5 7403.4 7661.1 8247.8 10251.6 11886.5 13226.3 13610.6 13631.5 11499.5 11931.9 11937.9 11943.2 12006.3 9012.5 11262.9 11494.9 11709.5 11969.5 10744.7 11504.1 12054.8 12075.0 12078.9 7433.8 7564.9 7588.4 7655.3 7658.9 9438.5 11667.1 11960.8 12002.5 12056.9 09:24:55 INFO - PROCESS | 1688 | 09:24:55 INFO - PROCESS | 1688 | [#3] mootools.html Cycles:180 Average:5597.92 Median:4916.35 stddev:3144.88 (64.0%) stddev-sans-first:3137.65 09:24:55 INFO - PROCESS | 1688 | Values: 1370.1 1506.8 1682.6 1883.6 1903.2 8224.0 8721.1 8731.0 8742.2 8743.1 7709.0 7945.9 8323.9 8537.7 8540.7 2267.5 2307.0 2311.0 2311.5 2315.6 2260.9 2278.3 2304.9 2307.0 2308.6 1899.5 2271.7 2282.8 2301.7 2302.0 2262.0 2276.1 2280.1 2280.3 2281.8 2251.7 2255.2 2256.6 2257.7 2261.7 8571.4 8659.5 8671.6 8701.3 8704.7 5130.4 5308.4 5315.1 5320.5 5332.4 3384.3 3443.1 3598.5 3598.8 3601.1 1073.4 1073.5 1077.0 1098.8 1100.8 11263.1 11410.9 11419.1 11430.5 11478.8 3628.7 4546.5 4728.1 4752.0 4755.7 9745.6 9763.0 9772.6 9776.8 9796.0 4716.7 5051.9 5302.8 5312.3 5319.3 4354.8 4508.9 4727.9 4847.9 4863.1 3845.0 3876.7 3978.7 4005.3 4018.1 1280.3 1285.8 1288.5 1301.0 1305.8 673.7 932.7 935.4 946.6 946.6 5432.5 5646.9 5995.1 6035.5 6101.3 433.6 436.6 446.9 448.8 452.4 3950.4 4066.8 4070.9 4205.7 4228.3 10370.4 10511.4 10643.0 10653.2 10662.6 10105.9 10136.1 10423.2 10427.5 10441.9 7874.6 7935.8 8086.8 8095.7 8106.0 5925.7 5950.3 6020.7 6032.9 6104.2 9702.2 9710.3 9897.7 9928.0 9928.4 9535.0 10032.4 10126.5 10127.6 10167.0 6935.2 7122.1 7142.0 7456.7 7617.5 7785.0 7882.5 8041.8 8287.5 8315.5 6059.9 6315.9 7231.7 7420.9 7567.2 7306.7 7329.3 7354.5 7418.7 7486.3 6097.9 6813.3 6871.9 6921.1 7025.4 4941.9 4952.6 5023.7 5120.0 5121.7 4895.1 4937.6 5035.7 5110.8 5149.4 09:24:55 INFO - PROCESS | 1688 | 09:24:55 INFO - PROCESS | 1688 | [#4] prototype.html Cycles:180 Average:4281.14 Median:4678.95 stddev:1301.57 (27.8%) stddev-sans-first:1270.27 09:24:55 INFO - PROCESS | 1688 | Values: 289.3 301.3 311.8 315.9 319.4 4429.8 5055.9 5272.1 5409.3 5489.4 4929.6 5228.5 5354.4 5461.8 5482.6 3396.7 3649.5 4194.0 4197.3 4276.4 3643.1 3958.0 4207.6 4232.4 4238.9 3146.2 3803.3 4044.9 4154.8 4193.2 3467.1 4023.9 4027.4 4060.5 4116.5 3105.5 3372.9 3415.4 3520.3 3546.9 3910.2 4781.1 4990.0 5188.4 5392.9 4136.9 4477.5 4694.3 4762.0 4776.6 4750.7 5250.9 5297.9 5745.9 5788.3 3320.3 3906.0 3934.9 3935.1 3972.7 5629.2 5630.9 5844.7 5994.9 6053.7 3844.3 3949.1 3992.3 4028.6 4060.2 3825.3 4046.5 4061.3 4067.3 4068.3 3899.5 4294.3 4404.5 4563.8 4702.6 5065.2 5111.9 5388.5 5714.8 5806.7 1336.2 1362.6 1378.0 1380.8 1391.0 769.8 831.9 837.8 842.8 845.7 3682.6 4229.6 4392.4 4399.8 4405.6 4170.8 4671.7 4820.3 4827.9 4940.4 3967.1 4246.3 4530.4 4536.9 4553.6 2362.4 2379.5 2403.6 2429.2 2542.9 4947.4 4968.6 4971.3 4980.6 4999.7 4180.0 4488.8 4686.2 4917.5 4988.7 4224.7 4608.5 4959.4 4989.4 5094.8 4620.5 4874.8 4875.2 4882.5 4893.8 4262.7 4507.8 4609.8 4846.8 4892.4 4795.4 4835.8 4946.8 4977.6 5086.5 3370.2 3382.7 3386.5 3390.6 3451.3 4809.7 4814.4 4862.4 4915.3 4947.8 4085.4 4745.9 5152.6 5730.7 5751.5 5177.0 5471.7 5708.0 5776.7 5784.3 5676.2 5708.4 5712.7 5729.7 5742.9 4616.6 5358.9 5569.9 5851.4 5856.5 4504.7 4914.8 5032.3 5759.7 5766.7 09:24:55 INFO - PROCESS | 1688 | 09:24:55 INFO - PROCESS | 1688 | [#5] yui.html Cycles:180 Average:25589.35 Median:3572.12 stddev:40810.55 (1142.5%) stddev-sans-first:40882.28 09:24:55 INFO - PROCESS | 1688 | Values: 709.7 718.2 763.6 794.7 837.3 15137.0 16469.5 16500.7 16505.8 16506.7 14764.6 14842.9 14843.9 14866.5 14881.8 712.8 722.6 865.4 874.3 888.0 868.2 886.8 889.7 890.2 890.4 948.7 949.7 949.7 951.2 953.6 751.7 916.2 946.3 947.8 952.1 707.0 708.1 708.2 709.7 715.8 14877.4 14889.3 14921.8 14922.6 14931.3 13079.5 13084.6 14098.4 14106.4 14502.2 12933.1 13174.1 13215.3 13322.0 13726.7 4541.3 4598.1 4627.5 4717.3 4764.5 124504.6 126658.0 127472.0 127699.0 127763.7 11012.6 11178.4 11229.4 11309.6 11755.3 22747.0 22773.4 22776.5 22783.8 22813.3 8059.9 8102.1 8121.5 8147.7 8271.6 3985.3 4454.7 4458.4 4527.7 4612.2 3521.4 3622.9 3624.9 3633.0 3640.9 539.9 651.1 658.1 658.5 659.4 586.3 614.5 625.6 626.3 652.7 9060.7 9221.2 9237.6 9262.4 9397.1 542.9 561.7 575.5 621.7 644.8 3960.6 4375.0 4389.7 4644.4 4733.1 94364.1 140851.0 143770.6 161627.0 165003.0 86864.6 122489.0 135056.0 137958.0 138364.0 116680.0 118298.0 118869.0 119873.6 128529.1 8728.9 9600.5 9890.3 10065.3 10086.6 52558.5 66290.7 66417.7 67204.3 67350.2 84570.2 86821.7 89105.6 101667.0 105548.9 12792.2 13500.7 13599.3 13779.2 13979.8 14840.2 15222.5 15250.7 15353.5 15766.9 13984.3 14024.7 14217.4 14225.6 14604.1 13712.0 13750.7 14057.4 14110.4 14126.6 13523.2 13728.6 13729.9 13773.7 13775.9 9457.8 9873.9 11227.3 11555.6 12044.8 11326.7 11442.6 11554.4 11709.7 11715.9 09:24:55 INFO - PROCESS | 1688 | -------- Summary: end -------- 09:24:55 INFO - PROCESS | 1688 | 09:24:56 INFO - TEST-INFO | 1688: exit 0 09:24:56 INFO - TEST-OK | dromaeo_css | took 1118140ms 09:24:56 INFO - TEST-START | kraken 09:24:56 INFO - operating with platform_type : mac_ 09:24:56 INFO - Initialising browser for kraken test... 09:24:56 INFO - TEST-INFO | started process 1805 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmp22Ld8s/profile http://localhost:49271/getInfo.html) 09:24:58 INFO - PROCESS | 1805 | __metrics Screen width/height:1600/1200 09:24:58 INFO - PROCESS | 1805 | colorDepth:24 09:24:58 INFO - PROCESS | 1805 | Browser inner width/height: 1024/689 09:24:58 INFO - PROCESS | 1805 | __metrics 09:24:58 INFO - PROCESS | 1805 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:24:58 INFO - PROCESS | 1805 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:24:59 INFO - TEST-INFO | 1805: exit 0 09:24:59 INFO - Browser initialized. 09:24:59 INFO - Running cycle 1/1 for kraken test... 09:24:59 INFO - TEST-INFO | started process 1806 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/T/tmp22Ld8s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:25:11 INFO - PROCESS | 1806 | RSS: Main: 243630080 09:25:11 INFO - PROCESS | 1806 | 09:25:14 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 09:25:15 INFO - PROCESS | 1806 | RSS: Main: 294055936 09:25:15 INFO - PROCESS | 1806 | 09:25:17 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 09:25:18 INFO - PROCESS | 1806 | RSS: Main: 305618944 09:25:18 INFO - PROCESS | 1806 | 09:25:21 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 09:25:22 INFO - PROCESS | 1806 | RSS: Main: 395145216 09:25:22 INFO - PROCESS | 1806 | 09:25:24 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 09:25:25 INFO - PROCESS | 1806 | RSS: Main: 296484864 09:25:25 INFO - PROCESS | 1806 | 09:25:27 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:25:27 INFO - PROCESS | 1806 | RSS: Main: 300515328 09:25:27 INFO - PROCESS | 1806 | 09:25:30 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 09:25:31 INFO - PROCESS | 1806 | RSS: Main: 339644416 09:25:31 INFO - PROCESS | 1806 | 09:25:34 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 09:25:34 INFO - PROCESS | 1806 | RSS: Main: 341450752 09:25:34 INFO - PROCESS | 1806 | 09:25:37 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 09:25:38 INFO - PROCESS | 1806 | RSS: Main: 340008960 09:25:38 INFO - PROCESS | 1806 | 09:25:39 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:25:40 INFO - PROCESS | 1806 | RSS: Main: 316039168 09:25:40 INFO - PROCESS | 1806 | 09:25:42 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:25:42 INFO - PROCESS | 1806 | RSS: Main: 320708608 09:25:42 INFO - PROCESS | 1806 | 09:25:45 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:25:45 INFO - PROCESS | 1806 | RSS: Main: 325718016 09:25:45 INFO - PROCESS | 1806 | 09:25:48 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:25:49 INFO - PROCESS | 1806 | RSS: Main: 332988416 09:25:49 INFO - PROCESS | 1806 | 09:25:52 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:25:52 INFO - PROCESS | 1806 | RSS: Main: 335200256 09:25:52 INFO - PROCESS | 1806 | 09:25:54 INFO - PROCESS | 1806 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 09:25:54 INFO - PROCESS | 1806 | RSS: Main: 378298368 09:25:54 INFO - PROCESS | 1806 | 09:25:54 INFO - PROCESS | 1806 | __start_tp_report 09:25:54 INFO - PROCESS | 1806 | _x_x_mozilla_page_load 09:25:54 INFO - PROCESS | 1806 | _x_x_mozilla_page_load_details 09:25:54 INFO - PROCESS | 1806 | |i|pagename|runs| 09:25:54 INFO - PROCESS | 1806 | |0;ai-astar;103;110;112;112;113;113;112;113;113;113 09:25:54 INFO - PROCESS | 1806 | |1;audio-beat-detection;148;112;105;161;108;130;162;137;138;106 09:25:54 INFO - PROCESS | 1806 | |2;audio-dft;184;184;183;183;182;180;183;181;182;180 09:25:54 INFO - PROCESS | 1806 | |3;audio-fft;78;58;66;88;62;70;92;90;97;56 09:25:54 INFO - PROCESS | 1806 | |4;audio-oscillator;86;68;100;69;84;67;96;69;67;100 09:25:54 INFO - PROCESS | 1806 | |5;imaging-gaussian-blur;88;85;88;85;88;85;88;85;88;85 09:25:54 INFO - PROCESS | 1806 | |6;imaging-darkroom;93;93;93;94;94;93;92;94;94;93 09:25:54 INFO - PROCESS | 1806 | |7;imaging-desaturate;87;104;134;137;83;84;84;85;84;83 09:25:54 INFO - PROCESS | 1806 | |8;json-parse-financial;52;51;54;53;54;51;53;52;53;52 09:25:54 INFO - PROCESS | 1806 | |9;json-stringify-tinderbox;66;78;75;72;75;71;70;73;71;71 09:25:54 INFO - PROCESS | 1806 | |10;stanford-crypto-aes;86;83;87;84;86;79;85;87;86;84 09:25:54 INFO - PROCESS | 1806 | |11;stanford-crypto-ccm;138;142;155;151;135;137;137;155;227;127 09:25:54 INFO - PROCESS | 1806 | |12;stanford-crypto-pbkdf2;164;165;166;163;165;162;162;165;164;161 09:25:54 INFO - PROCESS | 1806 | |13;stanford-crypto-sha256-iterative;66;66;66;67;66;66;68;66;64;66 09:25:54 INFO - PROCESS | 1806 | __end_tp_report 09:25:54 INFO - PROCESS | 1806 | __start_cc_report 09:25:54 INFO - PROCESS | 1806 | _x_x_mozilla_cycle_collect,2137 09:25:54 INFO - PROCESS | 1806 | __end_cc_report 09:25:54 INFO - PROCESS | 1806 | __startTimestamp1470759954934__endTimestamp 09:25:54 INFO - PROCESS | 1806 | 09:25:54 INFO - PROCESS | 1806 | ------- Summary: start ------- 09:25:54 INFO - PROCESS | 1806 | Number of tests: 14 09:25:54 INFO - PROCESS | 1806 | 09:25:54 INFO - PROCESS | 1806 | [#0] ai-astar Cycles:10 Average:111.40 Median:113.00 stddev:3.10 (2.7%) stddev-sans-first:1.00 09:25:54 INFO - PROCESS | 1806 | Values: 103.0 110.0 112.0 112.0 113.0 113.0 112.0 113.0 113.0 113.0 09:25:54 INFO - PROCESS | 1806 | 09:25:54 INFO - PROCESS | 1806 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:137.50 stddev:22.18 (16.1%) stddev-sans-first:22.62 09:25:54 INFO - PROCESS | 1806 | Values: 148.0 112.0 105.0 161.0 108.0 130.0 162.0 137.0 138.0 106.0 09:25:54 INFO - PROCESS | 1806 | 09:25:54 INFO - PROCESS | 1806 | [#2] audio-dft Cycles:10 Average:182.20 Median:183.00 stddev:1.48 (0.8%) stddev-sans-first:1.41 09:25:54 INFO - PROCESS | 1806 | Values: 184.0 184.0 183.0 183.0 182.0 180.0 183.0 181.0 182.0 180.0 09:25:54 INFO - PROCESS | 1806 | 09:25:54 INFO - PROCESS | 1806 | [#3] audio-fft Cycles:10 Average:75.70 Median:83.00 stddev:15.26 (18.4%) stddev-sans-first:16.16 09:25:54 INFO - PROCESS | 1806 | Values: 78.0 58.0 66.0 88.0 62.0 70.0 92.0 90.0 97.0 56.0 09:25:54 INFO - PROCESS | 1806 | 09:25:54 INFO - PROCESS | 1806 | [#4] audio-oscillator Cycles:10 Average:80.60 Median:69.00 stddev:14.25 (20.7%) stddev-sans-first:14.98 09:25:54 INFO - PROCESS | 1806 | Values: 86.0 68.0 100.0 69.0 84.0 67.0 96.0 69.0 67.0 100.0 09:25:54 INFO - PROCESS | 1806 | 09:25:54 INFO - PROCESS | 1806 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:88.00 stddev:1.58 (1.8%) stddev-sans-first:1.58 09:25:54 INFO - PROCESS | 1806 | Values: 88.0 85.0 88.0 85.0 88.0 85.0 88.0 85.0 88.0 85.0 09:25:54 INFO - PROCESS | 1806 | 09:25:54 INFO - PROCESS | 1806 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:93.50 stddev:0.67 (0.7%) stddev-sans-first:0.71 09:25:54 INFO - PROCESS | 1806 | Values: 93.0 93.0 93.0 94.0 94.0 93.0 92.0 94.0 94.0 93.0 09:25:54 INFO - PROCESS | 1806 | 09:25:54 INFO - PROCESS | 1806 | [#7] imaging-desaturate Cycles:10 Average:96.50 Median:84.00 stddev:21.50 (25.6%) stddev-sans-first:22.52 09:25:54 INFO - PROCESS | 1806 | Values: 87.0 104.0 134.0 137.0 83.0 84.0 84.0 85.0 84.0 83.0 09:25:54 INFO - PROCESS | 1806 | 09:25:54 INFO - PROCESS | 1806 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:53.00 stddev:1.08 (2.0%) stddev-sans-first:1.13 09:25:54 INFO - PROCESS | 1806 | Values: 52.0 51.0 54.0 53.0 54.0 51.0 53.0 52.0 53.0 52.0 09:25:54 INFO - PROCESS | 1806 | 09:25:54 INFO - PROCESS | 1806 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:72.50 stddev:3.29 (4.5%) stddev-sans-first:2.62 09:25:54 INFO - PROCESS | 1806 | Values: 66.0 78.0 75.0 72.0 75.0 71.0 70.0 73.0 71.0 71.0 09:25:54 INFO - PROCESS | 1806 | 09:25:54 INFO - PROCESS | 1806 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:86.00 stddev:2.41 (2.8%) stddev-sans-first:2.51 09:25:54 INFO - PROCESS | 1806 | Values: 86.0 83.0 87.0 84.0 86.0 79.0 85.0 87.0 86.0 84.0 09:25:54 INFO - PROCESS | 1806 | 09:25:54 INFO - PROCESS | 1806 | [#11] stanford-crypto-ccm Cycles:10 Average:150.40 Median:146.50 stddev:28.44 (19.4%) stddev-sans-first:29.81 09:25:54 INFO - PROCESS | 1806 | Values: 138.0 142.0 155.0 151.0 135.0 137.0 137.0 155.0 227.0 127.0 09:25:54 INFO - PROCESS | 1806 | 09:25:54 INFO - PROCESS | 1806 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.70 Median:164.50 stddev:1.64 (1.0%) stddev-sans-first:1.73 09:25:54 INFO - PROCESS | 1806 | Values: 164.0 165.0 166.0 163.0 165.0 162.0 162.0 165.0 164.0 161.0 09:25:54 INFO - PROCESS | 1806 | 09:25:54 INFO - PROCESS | 1806 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:0.99 (1.5%) stddev-sans-first:1.05 09:25:54 INFO - PROCESS | 1806 | Values: 66.0 66.0 66.0 67.0 66.0 66.0 68.0 66.0 64.0 66.0 09:25:54 INFO - PROCESS | 1806 | -------- Summary: end -------- 09:25:54 INFO - PROCESS | 1806 | 09:25:55 INFO - TEST-INFO | 1806: exit 0 09:25:55 INFO - TEST-OK | kraken | took 59526ms 09:25:55 INFO - SUITE-END | took 1177s 09:25:56 INFO - Completed test suite (00:19:37) 09:25:56 INFO - javascript benchmark 09:25:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3424.5034470001847, 3425.554677891874, 3434.9656503434976, 3441.5181874537584, 3491.8952431427065, 5764.365919748824, 5882.499987501057, 5912.674802885845, 5918.082697181938, 5972.372900845408, 5741.368449470555, 5814.040683287263, 5920.674362910038, 6057.242844644419, 6081.966065768819, 5140.948590514085, 5173.922391164136, 5202.297689811865, 5223.320968274119, 5437.755301011444, 5386.48828361305, 5424.186372044185, 5431.293931788881, 5457.263269458632, 5477.7261136943, 5275.86810329741, 5281.339832520935, 5347.358317001946, 5368.62419630626, 5371.221172929903, 5318.202269659543, 5319.9202011969855, 5339.652922560022, 5376.865578360533, 5408.4591540845995, 5285.973570132163, 5324.733763311858, 5332.30680011596, 5366.731663416814, 5395.082835917903, 5757.568182386296, 5794.971025144847, 6023.036314189752, 6030.396960303979, 6061.151438798572, 6281.466075383598, 6312.684365781693, 6337.271213810379, 6343.524235682342, 6347.968260158716, 6314.147590075327, 6315.0843127746675, 6338.904916426257, 6349.936500635027, 6357.904631430532, 5850.795222167204, 5894.67579283135, 6060.151578778975, 6090.056041313604, 6108.877822443526, 6270.2789179244055, 6314.557980941382, 6318.336574659686, 6323.588966717149, 6326.367363263636, 6211.068339749074, 6238.906416403757, 6277.2781130170615, 6287.1512345832725, 6354.142190804275, 6284.371562843679, 6308.747650093945, 6330.177076980055, 6335.968320158462, 6352.968235158794, 6315.431611155018, 6323.08315294289, 6327.96836015817, 6328.303886572473, 6331.303556608769, 6246.250449946036, 6268.686565671698, 6294.339094395023, 6314.116023756677, 6318.936810631927, 5058.266551350033, 5063.721495317719, 5066.923996140061, 5073.847784566469, 5091.643584949092, 4542.886427839265, 4545.795439205178, 4557.977210113928, 4580.290055041474, 4581.770911454414, 5773.191753154561, 5836.737346819404, 5853.356130825606, 5884.176215329857, 5913.142594323799, 6314.4317011468265, 6321.336259692666, 6322.304546499881, 6326.2092238470195, 6331.271903731038, 5888.823335299948, 5898.557608179318, 5904.498117659961, 5911.261301673705, 5955.493783028404, 5483.835484935458, 5531.363893152258, 5575.637583557056, 5586.413426590231, 5598.468145526169, 6280.685965701788, 6296.5277604178955, 6325.4307112358265, 6349.333320001518, 6392.041193820778, 6221.191245138103, 6276.811695649138, 6283.2460104787715, 6291.339409362135, 6297.685115744102, 6211.565190436626, 6378.649174295459, 6385.265694445013, 6403.967980160256, 6419.614823110628, 6264.49884009269, 6314.210723659543, 6342.270638876404, 6355.713992870239, 6362.045693146065, 6312.747490100344, 6321.241451025906, 6325.462335701332, 6333.90499142504, 6352.809415717536, 6111.98679349419, 6318.462930650762, 6326.9683651581445, 6346.048092786119, 6346.07981842621, 4788.922492439173, 5793.739281732416, 6184.319724830175, 6282.780102696383, 6316.273628532594, 6164.0, 6165.414285642857, 6169.105479705508, 6171.889059969249, 6179.721912514037, 6099.461111717566, 6177.444030037318, 6184.721687523982, 6212.347703491249, 6311.810645680637, 6122.051082082285, 6136.151476879544, 6158.0, 6195.783147589813, 6219.689015549114], "value": 5828.32950207148, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [443.5675216663752, 512.8230760387667, 525.2515165888606, 532.4622131647038, 554.1217170784307, 31593.526097108563, 36413.45379819301, 41047.973800654996, 42506.787466062626, 42565.78717106418, 44410.333844992354, 44659.55340446594, 44677.7766111169, 44735.77632111835, 44997.0, 9633.566489507954, 9816.803663926717, 9818.9509052455, 9823.428251479765, 9828.017198280186, 8775.912240877573, 8974.14745599167, 8978.685745998891, 9131.2238459731, 9133.17801397874, 11624.360660163687, 11843.348615826126, 11854.229475084136, 12069.758604827899, 12158.148929574932, 11870.703232419215, 11976.700582485464, 11987.520499180022, 11990.880091199064, 11992.820107698391, 11747.588834390755, 11829.585964491287, 11834.526618935231, 11834.822477662841, 11843.348615826126, 41288.38067428961, 41585.0, 41627.167456651, 41628.1674366511, 41781.79109104465, 3392.7794693344854, 3425.6745609167087, 3572.8570857165682, 3622.4023036198996, 3862.903427414309, 31583.52624710631, 31927.042188734376, 33204.00387988364, 33245.50131748026, 33259.0, 3210.7752457327997, 3289.3585750778607, 3335.8999230023137, 3361.0757041813454, 3395.4567269236804, 374921.0, 380281.0, 380982.0, 382995.0, 388402.0, 60650.78698426007, 60863.39136608577, 60888.0, 60966.0, 61139.0, 55285.72357138189, 63076.0, 64776.67611661911, 64874.67562662157, 65029.67485162544, 16368.181590920407, 16533.090680012476, 16544.0, 16561.585960350847, 16569.83430165707, 12862.228266304051, 12934.741305173844, 12948.417321220568, 12961.02792290563, 13002.024848136427, 12191.878081219074, 14794.964352495223, 15210.771838422432, 16871.83128168727, 17056.48830535086, 6113.349395663235, 6307.148534947815, 6325.177726895474, 6385.45723613498, 6394.360563943662, 895.2166854002747, 959.8521469984759, 965.9082387173207, 989.0999190736395, 995.0248756218801, 30194.69805301919, 30208.244793880327, 30583.847080764455, 30994.69005309963, 30998.0, 1077.7844431113808, 1089.6131873184904, 1096.7916095941746, 1127.5997021057406, 1142.605800998654, 14171.574852754435, 15516.534503964898, 15687.607809804618, 16144.515664530083, 16208.78434117446, 663597.0, 664284.0, 664903.0, 665828.0, 666529.0, 53190.0, 59936.70031649901, 60558.0, 60823.69588152119, 61198.38801612016, 1371.3965855023757, 1398.1960372785688, 1419.8438171800888, 1420.2969530082676, 1424.430227908824, 26820.0, 30952.53571196479, 31587.36825263528, 32246.19384515312, 32497.512537311486, 53053.46946530485, 53451.93096138133, 53793.73103134616, 53843.46156538385, 53870.730646346514, 623802.0, 674435.0, 682294.0, 686044.0, 885703.0, 26301.868490657424, 26558.7344126564, 26578.867105665122, 26594.069207577642, 26680.8665956669, 45941.540584595066, 46101.76949115233, 46415.76792116018, 47050.0, 47472.52527474681, 34957.47563786495, 37773.81113094417, 38325.0, 38677.2264554713, 39543.80228098841, 38168.0, 38411.0, 38692.80653596714, 39614.60385396109, 40075.0, 37101.814490927376, 37150.0, 37240.0, 37274.813625931696, 38302.42546361751, 30072.0, 33774.49338259977, 34948.126296841765, 36086.0, 36101.81949090238, 25686.614700779875, 25693.0, 25734.742652573233, 26019.0, 26443.091259961773], "value": 20343.89893903997, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.19974466514714, 372.60503865902143, 376.1968197897489, 380.42365815789077, 405.28062688727505, 14777.778333324995, 15316.0, 20446.795532044674, 20541.0, 21021.474463138427, 19370.22519099238, 19958.90020549895, 20324.59350812983, 20553.486162845937, 21298.0, 5267.55225805807, 5820.53435725142, 6062.0, 6067.271927368722, 6072.362401947798, 5887.0, 6069.878602427949, 6076.665783381913, 6085.2393450818645, 6085.726142323606, 5241.689577605599, 5622.971885140569, 5910.0, 6033.155358249848, 6055.969720151393, 5946.910796338058, 6002.3697511761075, 6004.309504407005, 6004.909926351108, 6010.068439391901, 5021.0, 5101.515356041207, 5189.974050129763, 5207.765650545697, 5228.47715228478, 25882.0, 25979.480410391687, 26047.0, 26160.34599135018, 26380.86809565959, 2039.4493486758493, 2430.0765709030634, 2631.6052592111146, 3012.3824615953895, 3159.4786860168047, 12214.816777748341, 13419.926405887505, 16266.186690665538, 16375.754363684555, 16409.507714768577, 1795.6767781799401, 1852.536865783554, 1873.915673794669, 1877.3241633012105, 1951.4438385060332, 116591.0, 125562.0, 126397.36801315934, 132609.3369533156, 135109.0, 11520.0, 11558.653240402802, 11760.176787624785, 11766.52933882635, 11912.583059592873, 14845.183514906785, 14855.851441485664, 14857.9599428039, 14868.0, 14868.330925108396, 6992.440604751608, 8441.620127094173, 8611.181937715906, 8706.695265665672, 8716.38985271025, 8591.699290524926, 8597.226249637562, 8741.606627701769, 8774.990876049336, 8781.429207101519, 3333.3999880021343, 4517.186906356887, 4597.3333866589155, 4683.297505374221, 4687.648426368036, 1855.7030875060195, 1932.21745193197, 1936.8353689936232, 1961.3429501117, 1985.622731680976, 776.704852156177, 779.2324560308092, 790.6442101054548, 850.3664769746455, 965.6764983730388, 16404.26180821842, 17528.737068943978, 20830.479238018866, 20886.373408797455, 20890.686639700416, 906.3202598051462, 907.5144797533288, 916.5463095767504, 940.7695114696943, 1032.6179313653995, 9502.572384242587, 10584.57661693539, 10644.520996555173, 11301.378424186585, 11344.602938897098, 592251.0, 596919.0, 602184.0, 609494.0, 611509.0, 75428.62285688537, 76449.0, 107493.92506074838, 131236.0, 137360.0, 954.050719534082, 967.3615413826841, 990.7869807991317, 995.2485873165714, 996.1720243353527, 18905.527361816065, 19127.043647817274, 19139.712904306725, 19146.0, 19208.327708530134, 53470.66323341947, 54454.0, 54788.97321294575, 55529.72235138799, 55799.32602021946, 77886.05284867923, 79616.20383796089, 79630.20369796228, 81231.59384203042, 82641.58679206566, 6984.755533556301, 7187.460940429384, 7403.370713489414, 7661.118971318369, 8247.752567422987, 10251.641192558225, 11886.465109069939, 13226.338683066, 13610.59168224955, 13631.522896698965, 11499.54001839917, 11931.940340298243, 11937.940310298392, 11943.223690460092, 12006.339651319262, 9012.504312262627, 11262.887371126184, 11494.885051149382, 11709.531618735497, 11969.521219151136, 10744.73138171552, 11504.137189710638, 12054.819177712518, 12075.0, 12078.939605301917, 7433.776986690408, 7564.924350756422, 7588.392928565812, 7655.272749088827, 7658.923410765821, 9438.52807359643, 11667.124965627441, 11960.76078478408, 12002.519899204284, 12056.879431205576], "value": 9612.4755779198, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1370.1025828082604, 1506.7965824613677, 1682.621410182709, 1883.585611165543, 1903.1816318982833, 8223.958880205599, 8721.084286149959, 8730.956345218265, 8742.169493898085, 8743.081976392483, 7708.96145519273, 7945.880811787828, 8323.91676083239, 8537.74386768398, 8540.70107546236, 2267.455810605455, 2306.9653955190684, 2311.0062673050584, 2311.4799170186743, 2315.606346921019, 2260.8530445520987, 2278.28234106256, 2304.873231972241, 2306.9653955190684, 2308.572914010905, 1899.5346140195672, 2271.7273927128686, 2282.7603101674335, 2301.666258392532, 2301.9986305956963, 2262.0386335807216, 2276.1350686739015, 2280.076568989562, 2280.270313499681, 2281.8060464860423, 2251.6960210371553, 2255.188132272397, 2256.6163752162006, 2257.6500642400456, 2261.694671219381, 8571.400001999862, 8659.48043117415, 8671.56642167889, 8701.260392866614, 8704.738857834274, 5130.384353877558, 5308.362996440452, 5315.123004704219, 5320.494553017457, 5332.440093790135, 3384.3062172254877, 3443.1220038890174, 3598.4962105305276, 3598.7660802047785, 3601.0637234319265, 1073.431081526792, 1073.5222825842504, 1076.9730756731144, 1098.758273179899, 1100.8073587122221, 11263.098952083814, 11410.885891141148, 11419.143564232632, 11430.456888320137, 11478.770424591461, 3628.673419392215, 4546.52261512543, 4728.1252968201, 4751.95248047522, 4755.738434386073, 9745.610175592898, 9762.951185244028, 9772.609095636237, 9776.804463910681, 9795.951020244853, 4716.693414928019, 5051.924221136685, 5302.840914772563, 5312.335958005249, 5319.308489896289, 4354.847580334673, 4508.9098218036115, 4727.905441891143, 4847.878803029953, 4863.148948933993, 3844.96155038446, 3876.7092468064443, 3978.701597380208, 4005.2790497710107, 4018.1160144768105, 1280.3406245783242, 1285.7685616588917, 1288.452407726731, 1300.9544665936837, 1305.7584346895856, 673.6665350651459, 932.7234629225079, 935.3592788939462, 946.602426980656, 946.6118891254553, 5432.483914028161, 5646.943530564724, 5995.096280349286, 6035.456808887134, 6101.298350689639, 433.62924699382205, 436.6266841850236, 446.8726412972287, 448.83617479619704, 452.42767898608287, 3950.3876899080688, 4066.8169932353117, 4070.8778736637946, 4205.747655140702, 4228.323468245066, 10370.429626370622, 10511.421871796972, 10642.988916052962, 10653.201009924287, 10662.626808061681, 10105.898941010642, 10136.087752102347, 10423.166146708245, 10427.478626068818, 10441.895581044093, 7874.606269686493, 7935.801604959922, 8086.757397278091, 8095.745159500169, 8105.959470202612, 5925.674087925206, 5950.256217972754, 6020.668863212565, 6032.90950635732, 6104.206453161239, 9702.223822094074, 9710.271729620448, 9897.703068907944, 9928.007199280302, 9928.404295742279, 9534.951155372764, 10032.398056116657, 10126.493675316058, 10127.64553240633, 10167.0, 6935.167779866449, 7122.109736282965, 7142.0001199833105, 7456.701731930662, 7617.542947423172, 7784.9610751945875, 7882.487638303492, 8041.839163216586, 8287.50274983503, 8315.459495132796, 6059.939400606114, 6315.86314463401, 7231.674574644258, 7420.888686669812, 7567.167611562613, 7306.6711997959155, 7329.340359367682, 7354.485186036926, 7418.703251869865, 7486.288802563748, 6097.932861749087, 6813.2846051163915, 6871.896921546281, 6921.065656136358, 7025.367716905503, 4941.925871111865, 4952.579030782424, 5023.723695196799, 5119.97600479898, 5121.743912804271, 4895.069936712013, 4937.629677774109, 5035.723035233097, 5110.821121260738, 5149.356330458692], "value": 4431.245105284367, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.26238092863196, 301.2949697707365, 311.7552721113925, 315.8736505397842, 319.4170638584584, 4429.832669641206, 5055.949440505589, 5272.103742363798, 5409.296791417115, 5489.39616642169, 4929.605631549476, 5228.4771522847695, 5354.437784032679, 5461.808836690717, 5482.643628164166, 3396.7282617390674, 3649.5073165122767, 4194.0, 4197.307444271692, 4276.412770273986, 3643.0528062703756, 3958.010497375656, 4207.600277973587, 4232.365145228209, 4238.88055972014, 3146.244901223701, 3803.3344164771192, 4044.939325910114, 4154.792260386984, 4193.245215861144, 3467.11042481848, 4023.879283621496, 4027.436158937751, 4060.451839001741, 4116.547179810218, 3105.5186446100893, 3372.9325413491592, 3415.4364529852546, 3520.3135388599235, 3546.929061418783, 3910.198409326081, 4781.139394908915, 4989.975050124763, 5188.351456067991, 5392.946070539323, 4136.910860015703, 4477.507474177838, 4694.342792009122, 4761.952380476186, 4776.617870570346, 4750.691205071659, 5250.894982100336, 5297.920531192035, 5745.885082298372, 5788.292453726161, 3320.286138480238, 3905.9804700976315, 3934.940975885364, 3935.1145992151824, 3972.682185425159, 5629.155626655994, 5630.915536266959, 5844.73698683556, 5994.940050599525, 6053.727582258765, 3844.2888065707934, 3949.0917089069676, 3992.2813893499447, 4028.576999415078, 4060.2285565742413, 3825.3496905526126, 4046.47395838545, 4061.329880569732, 4067.349224124126, 4068.3083875741195, 3899.512560929884, 4294.291441912112, 4404.4714634243455, 4563.794629241692, 4702.553257440538, 5065.189569668835, 5111.948880511221, 5388.46115388458, 5714.828555143353, 5806.68063256525, 1336.1648969394128, 1362.5503583817317, 1378.0078343592597, 1380.7997840313099, 1390.9652258693613, 769.8183288651893, 831.9001719793664, 837.8491871463195, 842.7640260727006, 845.7335939179139, 3682.576503702055, 4229.555896630871, 4392.407025051577, 4399.758013309235, 4405.603495685404, 4170.769622961173, 4671.719696818134, 4820.325154478376, 4827.927581086286, 4940.407151141886, 3967.056142490159, 4246.299360605465, 4530.433695788027, 4536.8638940831825, 4553.612942899825, 2362.3975886148924, 2379.5121999990306, 2403.579373609611, 2429.1740808125323, 2542.872856357175, 4947.381577302837, 4968.627352948543, 4971.279164521125, 4980.576650984634, 4999.700017998932, 4180.0, 4488.753118578445, 4686.179918514246, 4917.532834380728, 4988.700677959334, 4224.725392849455, 4608.479201863377, 4959.380077490314, 4989.401271847401, 5094.821681241138, 4620.5148459411275, 4874.7562621868055, 4875.1712208923855, 4882.511748825231, 4893.755312234302, 4262.701610887332, 4507.842225522091, 4609.810340088431, 4846.781894814812, 4892.36105904526, 4795.448523419712, 4835.758212089452, 4946.752662366939, 4977.576905963034, 5086.54221120101, 3370.1574606348413, 3382.6955573998457, 3386.5428167197115, 3390.593128824557, 3451.2752322012657, 4809.735464549483, 4814.435308524813, 4862.353235073695, 4915.336429582104, 4947.77735001933, 4085.4484644572603, 4745.928811067768, 5152.5877929766275, 5730.656160630376, 5751.511121554715, 5177.0, 5471.671699698031, 5707.971460142673, 5776.718196312517, 5784.2769653793275, 5676.17695434168, 5708.373113662587, 5712.742926568231, 5729.713514324184, 5742.885142297214, 4616.584507394216, 5358.873667276098, 5569.913866795936, 5851.4066457830395, 5856.531477481706, 4504.662150338803, 4914.837471512523, 5032.2890217326285, 5759.709016782524, 5766.654000759968], "value": 3875.857435107734, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.7267551992483, 718.2350796401831, 763.5723994563042, 794.6900708723603, 837.2715737308542, 15137.0, 16469.50591482256, 16500.669986600275, 16505.83494165058, 16506.66986660269, 14764.6308842279, 14842.925785371084, 14843.925780371083, 14866.47967321144, 14881.776773348409, 712.8217945513621, 722.5953466058997, 865.3942240431708, 874.2961915657893, 888.0098689960712, 868.2272777228272, 886.7916039730658, 889.724185502493, 890.2121622364222, 890.3678388344251, 948.7248697877607, 949.6581230756922, 949.738821823997, 951.219999600328, 953.6042542344918, 751.6767789850362, 916.1891725822695, 946.3044662173297, 947.7725345916965, 952.0764858087679, 706.9540479868792, 708.0936401406157, 708.2138825903244, 709.6664567653195, 715.8389362393472, 14877.404903803836, 14889.255537223096, 14921.776173357408, 14922.552323430205, 14931.2534373282, 13079.542216022393, 13084.644763942486, 14098.436062557485, 14106.365213565414, 14502.202378869159, 12933.094683372168, 13174.143680660822, 13215.339233038405, 13322.0, 13726.65683357914, 4541.321554384054, 4598.149342371672, 4627.514111018362, 4717.268823332389, 4764.523547645243, 124504.64233751775, 126658.0, 127472.0, 127699.0, 127763.72236277611, 11012.559497620166, 11178.44107794607, 11229.38238396896, 11309.604163854226, 11755.294682319089, 22747.0, 22773.430664233525, 22776.544469110857, 22783.772162278496, 22813.31560053201, 8059.879101813477, 8102.108768035511, 8121.4721043132, 8147.67409303633, 8271.586420678943, 3985.282649123186, 4454.732716037048, 4458.4426946631675, 4527.660425468103, 4612.238980568236, 3521.3837578423672, 3622.8550857965915, 3624.8912532624063, 3632.9818350908076, 3640.890773276806, 539.8596364945064, 651.133991790917, 658.0819756439739, 658.5225711359226, 659.446065305146, 586.2788769813154, 614.5452365249772, 625.586487331878, 626.3204423200787, 652.7030201258416, 9060.682876099305, 9221.17009469151, 9237.584308706124, 9262.44425334482, 9397.059484735388, 542.9131338985743, 561.6994907724348, 575.529522071104, 621.6798348850346, 644.7711062572813, 3960.6039396060164, 4374.978125109417, 4389.670774691911, 4644.373009643722, 4733.053389322081, 94364.0563594369, 140851.0, 143770.5622943778, 161627.0, 165003.0, 86864.56567717121, 122489.0, 135056.0, 137958.0, 138364.0, 116680.0, 118298.04254893697, 118869.0, 119873.60252794895, 128529.07206391911, 8728.869066963875, 9600.519974001132, 9890.307678462726, 10065.345752526062, 10086.596536138471, 52558.47441525536, 66290.66854665696, 66417.66791166013, 67204.3279567198, 67350.24446821882, 84570.15429845623, 86821.69767453367, 89105.55447222722, 101666.98333016575, 105548.94451055699, 12792.168509046882, 13500.662483437993, 13599.320033998458, 13779.242141681903, 13979.790303145257, 14840.18378989166, 15222.543323700307, 15250.694986100438, 15353.539393818204, 15766.9211653941, 13984.300784960915, 14024.719505610035, 14217.431302748188, 14225.644358890697, 14604.12375257488, 13712.0401571893, 13750.65623359424, 14057.437702491785, 14110.36503357331, 14126.646833829236, 13523.188608683116, 13728.588142355746, 13729.862701372858, 13773.655658608615, 13775.931120344334, 9457.810843783223, 9873.901260987299, 11227.326360418401, 11555.595554155565, 12044.75910481803, 11326.716832078935, 11442.586000605796, 11554.364509952033, 11709.707257318636, 11715.882841171479], "value": 8019.8090631895375, "unit": "score"}], "name": "dromaeo_css", "value": 7344.917689081371, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 110.0, 112.0, 112.0, 113.0, 113.0, 112.0, 113.0, 113.0, 113.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 112.0, 105.0, 161.0, 108.0, 130.0, 162.0, 137.0, 138.0, 106.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 183.0, 183.0, 182.0, 180.0, 183.0, 181.0, 182.0, 180.0], "value": 182.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 66.0, 88.0, 62.0, 70.0, 92.0, 90.0, 97.0, 56.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 68.0, 100.0, 69.0, 84.0, 67.0, 96.0, 69.0, 67.0, 100.0], "value": 80.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 88.0, 85.0, 88.0, 85.0, 88.0, 85.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 94.0, 93.0, 92.0, 94.0, 94.0, 93.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 104.0, 134.0, 137.0, 83.0, 84.0, 84.0, 85.0, 84.0, 83.0], "value": 96.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 54.0, 53.0, 54.0, 51.0, 53.0, 52.0, 53.0, 52.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 78.0, 75.0, 72.0, 75.0, 71.0, 70.0, 73.0, 71.0, 71.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 87.0, 84.0, 86.0, 79.0, 85.0, 87.0, 86.0, 84.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 142.0, 155.0, 151.0, 135.0, 137.0, 137.0, 155.0, 227.0, 127.0], "value": 150.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 166.0, 163.0, 165.0, 162.0, 162.0, 165.0, 164.0, 161.0], "value": 163.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 66.0, 66.0, 68.0, 66.0, 64.0, 66.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1446.5, "alertThreshold": 2.0}]} 09:25:56 INFO - Return code: 0 09:25:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:25:56 INFO - # TBPL SUCCESS # 09:25:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:25:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:25:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:25:56 INFO - 2016-08-09 09:25:56.244 screenresolution[1816:9940] starting screenresolution argv=screenresolution get 09:25:56 INFO - 2016-08-09 09:25:56.246 screenresolution[1816:9940] Display 0: 1600x1200x32@60 09:25:56 INFO - 2016-08-09 09:25:56.252 screenresolution[1817:9943] starting screenresolution argv=screenresolution list 09:25:56 INFO - Available Modes on Display 0 09:25:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:25:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:25:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:25:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:25:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:25:56 INFO - Intel Iris: 09:25:56 INFO - Chipset Model: Intel Iris 09:25:56 INFO - Type: GPU 09:25:56 INFO - Bus: Built-In 09:25:56 INFO - VRAM (Dynamic, Max): 1536 MB 09:25:56 INFO - Vendor: Intel (0x8086) 09:25:56 INFO - Device ID: 0x0a2e 09:25:56 INFO - Revision ID: 0x0009 09:25:56 INFO - Displays: 09:25:56 INFO - Display: 09:25:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:25:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:25:56 INFO - Display Serial Number: 1600x1200 60 09:25:56 INFO - Main Display: Yes 09:25:56 INFO - Mirror: Off 09:25:56 INFO - Online: Yes 09:25:56 INFO - Rotation: Supported 09:25:56 INFO - Return code: 0 09:25:56 INFO - Running post-action listener: _resource_record_post_action 09:25:56 INFO - [mozharness: 2016-08-09 16:25:56.487976Z] Finished run-tests step (success) 09:25:56 INFO - Running post-run listener: _resource_record_post_run 09:25:56 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 31947776; Write bytes: 707778560; Read time: 204; Write time: 2006 09:25:56 INFO - TinderboxPrint: CPU usage
54.0% 09:25:56 INFO - TinderboxPrint: I/O read bytes / time
31,947,776 / 204 09:25:56 INFO - TinderboxPrint: I/O write bytes / time
707,778,560 / 2,006 09:25:56 INFO - TinderboxPrint: CPU idle
2,234.9 (46.0%) 09:25:56 INFO - TinderboxPrint: CPU system
976.4 (20.1%) 09:25:56 INFO - TinderboxPrint: CPU user
1,642.2 (33.8%) 09:25:56 INFO - TinderboxPrint: Swap in / out
434,532,352 / 0 09:25:56 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 396481024; Write bytes: 389523456; Read time: 29490; Write time: 805 09:25:56 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 30186496; Write bytes: 296292352; Read time: 189; Write time: 1148 09:25:56 INFO - Running post-run listener: _upload_blobber_files 09:25:56 INFO - Blob upload gear active. 09:25:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:25:56 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:25:56 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:25:56 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:25:57 INFO - (blobuploader) - INFO - Open directory for files ... 09:25:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:25:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:25:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:25:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:25:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:25:59 INFO - (blobuploader) - INFO - Done attempting. 09:25:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:25:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:25:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:26:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:26:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:26:00 INFO - (blobuploader) - INFO - Done attempting. 09:26:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:26:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:26:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:26:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:26:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:26:02 INFO - (blobuploader) - INFO - Done attempting. 09:26:02 INFO - (blobuploader) - INFO - Iteration through files over. 09:26:02 INFO - Return code: 0 09:26:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:26:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:26:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a018ca371b137f552157cebe16792b5afbc0d5c433bca73c4d2881ca8521591fb55798a1ac7efc0288077ce8651f67bcda41be6e345784222617bbe9a0b56ed7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04876925809c94e5bad65d3aadd81ec72c6804e3d25964fa6da7d310a46773a9ed263494cf9c9df3778deee7ba6cefe7e4f274bea7b7d07e433a24e400318762", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb374c4258da90b1434fa01f9a53eefba7f3b84df30869a12d8ad9ce798f6437013fc97cccdb1352c00989600d9f6a5613495af57f57e9950ce29be75f24132d"} 09:26:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:26:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:26:02 INFO - Contents: 09:26:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a018ca371b137f552157cebe16792b5afbc0d5c433bca73c4d2881ca8521591fb55798a1ac7efc0288077ce8651f67bcda41be6e345784222617bbe9a0b56ed7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04876925809c94e5bad65d3aadd81ec72c6804e3d25964fa6da7d310a46773a9ed263494cf9c9df3778deee7ba6cefe7e4f274bea7b7d07e433a24e400318762", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb374c4258da90b1434fa01f9a53eefba7f3b84df30869a12d8ad9ce798f6437013fc97cccdb1352c00989600d9f6a5613495af57f57e9950ce29be75f24132d"} 09:26:02 INFO - Running post-run listener: copy_logs_to_upload_dir 09:26:02 INFO - Copying logs to upload dir... 09:26:02 INFO - mkdir: /builds/slave/test/build/upload/logs 09:26:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.866084 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-08-09 09:26:03.052871) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 09:26:03.055996) ========= 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.AWs7OVi6Yq/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.NDvhIEhgcs/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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/Firefox/sha512/a018ca371b137f552157cebe16792b5afbc0d5c433bca73c4d2881ca8521591fb55798a1ac7efc0288077ce8651f67bcda41be6e345784222617bbe9a0b56ed7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04876925809c94e5bad65d3aadd81ec72c6804e3d25964fa6da7d310a46773a9ed263494cf9c9df3778deee7ba6cefe7e4f274bea7b7d07e433a24e400318762", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb374c4258da90b1434fa01f9a53eefba7f3b84df30869a12d8ad9ce798f6437013fc97cccdb1352c00989600d9f6a5613495af57f57e9950ce29be75f24132d"} build_url:https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011776 build_url: 'https://queue.taskcluster.net/v1/task/XgOsSOVOTv2086ZWg2c2jw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a018ca371b137f552157cebe16792b5afbc0d5c433bca73c4d2881ca8521591fb55798a1ac7efc0288077ce8651f67bcda41be6e345784222617bbe9a0b56ed7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04876925809c94e5bad65d3aadd81ec72c6804e3d25964fa6da7d310a46773a9ed263494cf9c9df3778deee7ba6cefe7e4f274bea7b7d07e433a24e400318762", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cb374c4258da90b1434fa01f9a53eefba7f3b84df30869a12d8ad9ce798f6437013fc97cccdb1352c00989600d9f6a5613495af57f57e9950ce29be75f24132d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-09 09:26:03.089333) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 09:26:03.089696) ========= 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.AWs7OVi6Yq/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.NDvhIEhgcs/Listeners TMPDIR=/var/folders/2t/z2gyjfx51g106820y9w1z5ph00000w/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.004756 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-09 09:26:03.138790) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 09:26:03.139125) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-09 09:26:03.139662) ========= ========= Total master_lag: 0.17 =========