builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0226 starttime: 1468431673.1 results: success (0) buildid: 20160713082525 builduid: e1a1b7a4f58c4eb9acb7891e12227f1d revision: 81bd8453740f30844982f13ca739ce960889d208 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 10:41:13.099291) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 10:41:13.099896) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 10:41:13.100221) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 10:41:13.127499) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 10:41:13.128176) ========= 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.JtlgCsAIRx/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.G4v9yvcsid/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-13 10:41:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2016-07-13 10:41:13 (18.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.394801 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 10:41:13.547683) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 10:41:13.548121) ========= 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.JtlgCsAIRx/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.G4v9yvcsid/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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.029624 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 10:41:13.599923) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 10:41:13.600338) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 81bd8453740f30844982f13ca739ce960889d208 --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 81bd8453740f30844982f13ca739ce960889d208 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JtlgCsAIRx/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.G4v9yvcsid/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-13 10:41:13,686 truncating revision to first 12 chars 2016-07-13 10:41:13,686 Setting DEBUG logging. 2016-07-13 10:41:13,686 attempt 1/10 2016-07-13 10:41:13,686 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/81bd8453740f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 10:41:14,331 unpacking tar archive at: mozilla-central-81bd8453740f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.935954 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 10:41:14.558773) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 10:41:14.559224) ========= script_repo_revision: 81bd8453740f30844982f13ca739ce960889d208 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 10:41:14.559770) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 10:41:14.560094) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 10:41:14.581347) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 57 secs) (at 2016-07-13 10:41:14.581797) ========= /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.JtlgCsAIRx/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.G4v9yvcsid/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 10:41:14 INFO - MultiFileLogger online at 20160713 10:41:14 in /builds/slave/test 10:41:14 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 10:41:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:41:14 INFO - {'append_to_log': False, 10:41:14 INFO - 'base_work_dir': '/builds/slave/test', 10:41:14 INFO - 'blob_upload_branch': 'Firefox', 10:41:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:41:14 INFO - 'branch': 'Firefox', 10:41:14 INFO - 'buildbot_json_path': 'buildprops.json', 10:41:14 INFO - 'config_files': ('talos/mac_config.py',), 10:41:14 INFO - 'default_actions': ('clobber', 10:41:14 INFO - 'read-buildbot-config', 10:41:14 INFO - 'download-and-extract', 10:41:14 INFO - 'populate-webroot', 10:41:14 INFO - 'create-virtualenv', 10:41:14 INFO - 'install', 10:41:14 INFO - 'run-tests'), 10:41:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:41:14 INFO - 'download_minidump_stackwalk': True, 10:41:14 INFO - 'download_symbols': 'ondemand', 10:41:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:41:14 INFO - 'tooltool.py': '/tools/tooltool.py', 10:41:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:41:14 INFO - '/tools/misc-python/virtualenv.py')}, 10:41:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:41:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:41:14 INFO - 'installer_path': 'installer.exe', 10:41:14 INFO - 'log_level': 'info', 10:41:14 INFO - 'log_name': 'talos', 10:41:14 INFO - 'log_to_console': True, 10:41:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:41:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:41:14 INFO - 'opt_config_files': (), 10:41:14 INFO - 'pip_index': False, 10:41:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:41:14 INFO - 'cmd': ('bash', 10:41:14 INFO - '-c', 10:41:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:41:14 INFO - 'enabled': True, 10:41:14 INFO - 'halt_on_failure': False, 10:41:14 INFO - 'name': 'check_screen_resolution'},), 10:41:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:41:14 INFO - 'cmd': ('bash', 10:41:14 INFO - '-c', 10:41:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:41:14 INFO - 'enabled': True, 10:41:14 INFO - 'halt_on_failure': False, 10:41:14 INFO - 'name': 'check_screen_resolution'},), 10:41:14 INFO - 'run_cmd_checks_enabled': True, 10:41:14 INFO - 'sps_profile': False, 10:41:14 INFO - 'sps_profile_interval': 0, 10:41:14 INFO - 'suite': 'dromaeojs', 10:41:14 INFO - 'system_bits': '32', 10:41:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:41:14 INFO - 'title': 't-yosemite-r7-0226', 10:41:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:41:14 INFO - 'use_talos_json': True, 10:41:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:41:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:41:14 INFO - 'work_dir': 'build'} 10:41:14 INFO - ##### 10:41:14 INFO - ##### Running clobber step. 10:41:14 INFO - ##### 10:41:14 INFO - Running pre-action listener: _resource_record_pre_action 10:41:14 INFO - Running main action method: clobber 10:41:14 INFO - rmtree: /builds/slave/test/build 10:41:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:41:16 INFO - Running post-action listener: _resource_record_post_action 10:41:16 INFO - ##### 10:41:16 INFO - ##### Finished clobber step (success) 10:41:16 INFO - ##### 10:41:16 INFO - ##### 10:41:16 INFO - ##### Running read-buildbot-config step. 10:41:16 INFO - ##### 10:41:16 INFO - Running pre-action listener: _resource_record_pre_action 10:41:16 INFO - Running main action method: read_buildbot_config 10:41:16 INFO - Using buildbot properties: 10:41:16 INFO - { 10:41:16 INFO - "project": "", 10:41:16 INFO - "product": "firefox", 10:41:16 INFO - "script_repo_revision": "production", 10:41:16 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 10:41:16 INFO - "repository": "", 10:41:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 10:41:16 INFO - "buildid": "20160713082525", 10:41:16 INFO - "pgo_build": "False", 10:41:16 INFO - "basedir": "/builds/slave/test", 10:41:16 INFO - "buildnumber": 9, 10:41:16 INFO - "slavename": "t-yosemite-r7-0226", 10:41:16 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:41:16 INFO - "platform": "yosemite_r7", 10:41:16 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:41:16 INFO - "branch": "mozilla-central", 10:41:16 INFO - "repo_path": "mozilla-central", 10:41:16 INFO - "revision": "81bd8453740f30844982f13ca739ce960889d208", 10:41:16 INFO - "stage_platform": "macosx64", 10:41:16 INFO - "builduid": "e1a1b7a4f58c4eb9acb7891e12227f1d", 10:41:16 INFO - "slavebuilddir": "test" 10:41:16 INFO - } 10:41:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 10:41:16 INFO - Running post-action listener: _resource_record_post_action 10:41:16 INFO - ##### 10:41:16 INFO - ##### Finished read-buildbot-config step (success) 10:41:16 INFO - ##### 10:41:16 INFO - ##### 10:41:16 INFO - ##### Running download-and-extract step. 10:41:16 INFO - ##### 10:41:16 INFO - Running pre-action listener: _resource_record_pre_action 10:41:16 INFO - Running main action method: download_and_extract 10:41:16 INFO - mkdir: /builds/slave/test/build/tests 10:41:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:16 INFO - https://queue.taskcluster.net/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:41:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:41:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:41:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 10:41:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 10:41:18 INFO - Downloaded 1199 bytes. 10:41:18 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 10:41:18 INFO - Using the following test package requirements: 10:41:18 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:41:18 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:41:18 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 10:41:18 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:41:18 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 10:41:18 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:41:18 INFO - u'jsshell-mac.zip'], 10:41:18 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:41:18 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 10:41:18 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:41:18 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:41:18 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 10:41:18 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:41:18 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 10:41:18 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:41:18 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 10:41:18 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:41:18 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 10:41:18 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:41:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:18 INFO - https://queue.taskcluster.net/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:41:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:41:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:41:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:41:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:41:19 INFO - Downloaded 18822865 bytes. 10:41:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:41:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:41:22 INFO - Return code: 0 10:41:22 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:22 INFO - https://queue.taskcluster.net/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:41:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:41:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:41:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:41:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:41:24 INFO - Downloaded 18822865 bytes. 10:41:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:41:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:41:27 INFO - Return code: 0 10:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:27 INFO - https://queue.taskcluster.net/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:41:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:41:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:41:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:41:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:41:28 INFO - Downloaded 14403317 bytes. 10:41:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:41:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 10:41:29 INFO - Return code: 0 10:41:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:29 INFO - https://queue.taskcluster.net/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:41:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:41:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:41:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 10:41:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 10:41:32 INFO - Downloaded 112768659 bytes. 10:41:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:41:32 INFO - mkdir: /builds/slave/test/properties 10:41:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:41:32 INFO - Writing to file /builds/slave/test/properties/build_url 10:41:32 INFO - Contents: 10:41:32 INFO - build_url:https://queue.taskcluster.net/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:41:33 INFO - Running post-action listener: _resource_record_post_action 10:41:33 INFO - Running post-action listener: set_extra_try_arguments 10:41:33 INFO - ##### 10:41:33 INFO - ##### Finished download-and-extract step (success) 10:41:33 INFO - ##### 10:41:33 INFO - ##### 10:41:33 INFO - ##### Running populate-webroot step. 10:41:33 INFO - ##### 10:41:33 INFO - Running pre-action listener: _resource_record_pre_action 10:41:33 INFO - Running main action method: populate_webroot 10:41:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:41:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:41:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:41:33 INFO - '--tppagecycles', 10:41:33 INFO - '7'], 10:41:33 INFO - 'tests': ['tsvgm']}}, 10:41:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:41:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:41:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:41:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:41:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:41:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:41:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:33 INFO - 'talos_options': ['--disable-e10s'], 10:41:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:41:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:41:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:33 INFO - 'talos_options': ['--disable-e10s'], 10:41:33 INFO - 'tests': ['damp', 'tps']}, 10:41:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:33 INFO - 'tests': ['damp', 'tps']}, 10:41:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:41:33 INFO - 'tests': ['dromaeo_dom']}, 10:41:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:41:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:41:33 INFO - 'tests': ['basic_compositor_video']}, 10:41:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:41:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:41:33 INFO - 'tests': ['a11yr', 10:41:33 INFO - 'ts_paint', 10:41:33 INFO - 'tpaint', 10:41:33 INFO - 'sessionrestore', 10:41:33 INFO - 'sessionrestore_no_auto_restore', 10:41:33 INFO - 'tabpaint']}, 10:41:33 INFO - 'other-e10s': {'tests': ['a11yr', 10:41:33 INFO - 'ts_paint', 10:41:33 INFO - 'tpaint', 10:41:33 INFO - 'sessionrestore', 10:41:33 INFO - 'sessionrestore_no_auto_restore', 10:41:33 INFO - 'tabpaint']}, 10:41:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:41:33 INFO - 'ts_paint', 10:41:33 INFO - 'tpaint', 10:41:33 INFO - 'sessionrestore', 10:41:33 INFO - 'sessionrestore_no_auto_restore', 10:41:33 INFO - 'tabpaint']}, 10:41:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:41:33 INFO - 'ts_paint', 10:41:33 INFO - 'tpaint', 10:41:33 INFO - 'sessionrestore', 10:41:33 INFO - 'sessionrestore_no_auto_restore', 10:41:33 INFO - 'tabpaint']}, 10:41:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:41:33 INFO - 'tests': ['a11yr', 10:41:33 INFO - 'ts_paint', 10:41:33 INFO - 'tpaint', 10:41:33 INFO - 'sessionrestore', 10:41:33 INFO - 'sessionrestore_no_auto_restore', 10:41:33 INFO - 'tabpaint']}, 10:41:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:41:33 INFO - 'tests': ['a11yr', 10:41:33 INFO - 'ts_paint', 10:41:33 INFO - 'tpaint', 10:41:33 INFO - 'sessionrestore', 10:41:33 INFO - 'sessionrestore_no_auto_restore', 10:41:33 INFO - 'tabpaint']}, 10:41:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:41:33 INFO - 'tests': ['tsvgx', 10:41:33 INFO - 'tsvgr_opacity', 10:41:33 INFO - 'tart', 10:41:33 INFO - 'tscrollx', 10:41:33 INFO - 'cart']}, 10:41:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:41:33 INFO - 'tsvgr_opacity', 10:41:33 INFO - 'tart', 10:41:33 INFO - 'tscrollx', 10:41:33 INFO - 'cart']}, 10:41:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:33 INFO - 'talos_options': ['--disable-e10s'], 10:41:33 INFO - 'tests': ['tp5o']}, 10:41:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:33 INFO - 'tests': ['tp5o']}, 10:41:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:33 INFO - 'talos_options': ['--disable-e10s', 10:41:33 INFO - '--xperf_path', 10:41:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:41:33 INFO - 'tests': ['tp5n']}, 10:41:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:41:33 INFO - 'talos_options': ['--xperf_path', 10:41:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:41:33 INFO - 'tests': ['tp5n']}}, 10:41:33 INFO - 'talos.zip': {'path': '', 10:41:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:41:33 INFO - Running post-action listener: _resource_record_post_action 10:41:33 INFO - ##### 10:41:33 INFO - ##### Finished populate-webroot step (success) 10:41:33 INFO - ##### 10:41:33 INFO - ##### 10:41:33 INFO - ##### Running create-virtualenv step. 10:41:33 INFO - ##### 10:41:33 INFO - Running pre-action listener: _resource_record_pre_action 10:41:33 INFO - Running main action method: create_virtualenv 10:41:33 INFO - Creating virtualenv /builds/slave/test/build/venv 10:41:33 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 10:41:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:41:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:41:33 INFO - Using real prefix '/tools/python27' 10:41:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:41:34 INFO - Installing distribute.............................................................................................................................................................................................done. 10:41:37 INFO - Installing pip.................done. 10:41:37 INFO - Return code: 0 10:41:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:37 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 0x1029a4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a39618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfcb5da690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102908d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102900b20>, '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.JtlgCsAIRx/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.G4v9yvcsid/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 10:41:37 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 10:41:37 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 10:41:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtlgCsAIRx/Render', 10:41:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:37 INFO - 'HOME': '/Users/cltbld', 10:41:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:37 INFO - 'LOGNAME': 'cltbld', 10:41:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:37 INFO - 'MOZ_NO_REMOTE': '1', 10:41:37 INFO - 'NO_EM_RESTART': '1', 10:41:37 INFO - 'PAGER': '/bin/cat', 10:41:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:37 INFO - 'PWD': '/builds/slave/test', 10:41:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:37 INFO - 'SHELL': '/bin/bash', 10:41:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G4v9yvcsid/Listeners', 10:41:37 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 10:41:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:37 INFO - 'USER': 'cltbld', 10:41:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:37 INFO - 'XPC_FLAGS': '0x0', 10:41:37 INFO - 'XPC_SERVICE_NAME': '0', 10:41:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:37 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 10:41:37 INFO - Cleaning up... 10:41:37 INFO - Return code: 0 10:41:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:37 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 0x1029a4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a39618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfcb5da690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102908d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102900b20>, '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.JtlgCsAIRx/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.G4v9yvcsid/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 10:41:37 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 10:41:37 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 10:41:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtlgCsAIRx/Render', 10:41:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:37 INFO - 'HOME': '/Users/cltbld', 10:41:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:37 INFO - 'LOGNAME': 'cltbld', 10:41:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:37 INFO - 'MOZ_NO_REMOTE': '1', 10:41:37 INFO - 'NO_EM_RESTART': '1', 10:41:37 INFO - 'PAGER': '/bin/cat', 10:41:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:37 INFO - 'PWD': '/builds/slave/test', 10:41:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:37 INFO - 'SHELL': '/bin/bash', 10:41:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G4v9yvcsid/Listeners', 10:41:37 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 10:41:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:37 INFO - 'USER': 'cltbld', 10:41:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:37 INFO - 'XPC_FLAGS': '0x0', 10:41:37 INFO - 'XPC_SERVICE_NAME': '0', 10:41:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:38 INFO - Downloading/unpacking psutil>=3.1.1 10:41:38 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 10:41:38 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 10:41:38 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 10:41:38 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 10:41:38 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 10:41:38 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 10:41:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:41:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:41:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:41:41 INFO - Installing collected packages: psutil 10:41:41 INFO - Running setup.py install for psutil 10:41:41 INFO - building 'psutil._psutil_osx' extension 10:41:41 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 10:41:41 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 10:41:41 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 10:41:41 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 10:41:41 INFO - building 'psutil._psutil_posix' extension 10:41:41 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 10:41:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:41:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:41:42 INFO - ^ 10:41:42 INFO - 1 warning generated. 10:41:42 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 10:41:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:41:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:41:42 INFO - Successfully installed psutil 10:41:42 INFO - Cleaning up... 10:41:42 INFO - Return code: 0 10:41:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:41:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1029a4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a39618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfcb5da690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102908d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102900b20>, '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.JtlgCsAIRx/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.G4v9yvcsid/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 10:41: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:41: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 mozsystemmonitor==0.3 10:41:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtlgCsAIRx/Render', 10:41:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:42 INFO - 'HOME': '/Users/cltbld', 10:41:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:42 INFO - 'LOGNAME': 'cltbld', 10:41:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:42 INFO - 'MOZ_NO_REMOTE': '1', 10:41:42 INFO - 'NO_EM_RESTART': '1', 10:41:42 INFO - 'PAGER': '/bin/cat', 10:41:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:42 INFO - 'PWD': '/builds/slave/test', 10:41:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:42 INFO - 'SHELL': '/bin/bash', 10:41:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G4v9yvcsid/Listeners', 10:41:42 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 10:41:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:42 INFO - 'USER': 'cltbld', 10:41:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:42 INFO - 'XPC_FLAGS': '0x0', 10:41:42 INFO - 'XPC_SERVICE_NAME': '0', 10:41:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:41:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:41:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:41:45 INFO - Installing collected packages: mozsystemmonitor 10:41:45 INFO - Running setup.py install for mozsystemmonitor 10:41:45 INFO - Successfully installed mozsystemmonitor 10:41:45 INFO - Cleaning up... 10:41:45 INFO - Return code: 0 10:41:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:41:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:45 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 0x1029a4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a39618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfcb5da690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102908d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102900b20>, '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.JtlgCsAIRx/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.G4v9yvcsid/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 10:41:45 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 10:41:45 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 10:41:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtlgCsAIRx/Render', 10:41:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:45 INFO - 'HOME': '/Users/cltbld', 10:41:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:45 INFO - 'LOGNAME': 'cltbld', 10:41:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:45 INFO - 'MOZ_NO_REMOTE': '1', 10:41:45 INFO - 'NO_EM_RESTART': '1', 10:41:45 INFO - 'PAGER': '/bin/cat', 10:41:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:45 INFO - 'PWD': '/builds/slave/test', 10:41:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:45 INFO - 'SHELL': '/bin/bash', 10:41:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G4v9yvcsid/Listeners', 10:41:45 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 10:41:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:45 INFO - 'USER': 'cltbld', 10:41:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:45 INFO - 'XPC_FLAGS': '0x0', 10:41:45 INFO - 'XPC_SERVICE_NAME': '0', 10:41:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:45 INFO - Downloading/unpacking jsonschema==2.5.1 10:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:41:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:41:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:41:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:41:47 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 10:41:47 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 10:41:47 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 10:41:47 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 10:41:47 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 10:41:47 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 10:41:48 INFO - Downloading functools32-3.2.3-2.tar.gz 10:41:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:41:48 INFO - warning: no files found matching '*.txt' 10:41:48 INFO - no previously-included directories found matching 'build' 10:41:48 INFO - no previously-included directories found matching 'dist' 10:41:48 INFO - no previously-included directories found matching '.git*' 10:41:48 INFO - Installing collected packages: jsonschema, functools32 10:41:48 INFO - Running setup.py install for functools32 10:41:48 INFO - warning: no files found matching '*.txt' 10:41:48 INFO - no previously-included directories found matching 'build' 10:41:48 INFO - no previously-included directories found matching 'dist' 10:41:48 INFO - no previously-included directories found matching '.git*' 10:41:48 INFO - Successfully installed jsonschema functools32 10:41:48 INFO - Cleaning up... 10:41:48 INFO - Return code: 0 10:41:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:48 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 0x1029a4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a39618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfcb5da690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102908d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102900b20>, '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.JtlgCsAIRx/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.G4v9yvcsid/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 10:41:48 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 10:41:48 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 10:41:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtlgCsAIRx/Render', 10:41:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:48 INFO - 'HOME': '/Users/cltbld', 10:41:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:48 INFO - 'LOGNAME': 'cltbld', 10:41:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:48 INFO - 'MOZ_NO_REMOTE': '1', 10:41:48 INFO - 'NO_EM_RESTART': '1', 10:41:48 INFO - 'PAGER': '/bin/cat', 10:41:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:48 INFO - 'PWD': '/builds/slave/test', 10:41:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:48 INFO - 'SHELL': '/bin/bash', 10:41:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G4v9yvcsid/Listeners', 10:41:48 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 10:41:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:48 INFO - 'USER': 'cltbld', 10:41:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:48 INFO - 'XPC_FLAGS': '0x0', 10:41:48 INFO - 'XPC_SERVICE_NAME': '0', 10:41:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:49 INFO - Downloading/unpacking blobuploader==1.2.4 10:41:49 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 10:41:49 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 10:41:49 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 10:41:49 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 10:41:49 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 10:41:49 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 10:41:51 INFO - Downloading blobuploader-1.2.4.tar.gz 10:41:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:41:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:41:51 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 10:41:51 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 10:41:51 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 10:41:51 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 10:41:51 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 10:41:51 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 10:41:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:41:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:41:52 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 10:41:52 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 10:41:52 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 10:41:52 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 10:41:52 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 10:41:52 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 10:41:52 INFO - Downloading docopt-0.6.1.tar.gz 10:41:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:41:52 INFO - Installing collected packages: blobuploader, requests, docopt 10:41:52 INFO - Running setup.py install for blobuploader 10:41:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:41:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:41:52 INFO - Running setup.py install for requests 10:41:53 INFO - Running setup.py install for docopt 10:41:53 INFO - Successfully installed blobuploader requests docopt 10:41:53 INFO - Cleaning up... 10:41:53 INFO - Return code: 0 10:41:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:41:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:53 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 0x1029a4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a39618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfcb5da690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102908d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102900b20>, '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.JtlgCsAIRx/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.G4v9yvcsid/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 10:41:53 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 10:41:53 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 10:41:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtlgCsAIRx/Render', 10:41:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:53 INFO - 'HOME': '/Users/cltbld', 10:41:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:53 INFO - 'LOGNAME': 'cltbld', 10:41:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:53 INFO - 'MOZ_NO_REMOTE': '1', 10:41:53 INFO - 'NO_EM_RESTART': '1', 10:41:53 INFO - 'PAGER': '/bin/cat', 10:41:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:53 INFO - 'PWD': '/builds/slave/test', 10:41:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:53 INFO - 'SHELL': '/bin/bash', 10:41:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G4v9yvcsid/Listeners', 10:41:53 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 10:41:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:53 INFO - 'USER': 'cltbld', 10:41:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:53 INFO - 'XPC_FLAGS': '0x0', 10:41:53 INFO - 'XPC_SERVICE_NAME': '0', 10:41:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:41:53 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-Ji5uib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:41:54 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-1Isr_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:41:54 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-Nz_EJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:41:54 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-BNTUrk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:41:54 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-j21m7m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:41:54 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-fGCLMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:41:54 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-n1nAbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:41:54 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-4bs81P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:41:54 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-8duBKT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:41:54 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-2krYFS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:41:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-AzTwJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:41:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-L53OET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:41:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-AJehrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:41:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-QNUXPi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-hiyKdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:41:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-3DC8oA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:41:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:41:55 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-2vdpxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:41:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:41:55 INFO - Running setup.py install for manifestparser 10:41:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:41:55 INFO - Running setup.py install for mozcrash 10:41:56 INFO - Running setup.py install for mozdebug 10:41:56 INFO - Running setup.py install for mozdevice 10:41:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:41:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:41:56 INFO - Running setup.py install for mozfile 10:41:56 INFO - Running setup.py install for mozhttpd 10:41:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:41:56 INFO - Running setup.py install for mozinfo 10:41:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:41:56 INFO - Running setup.py install for mozInstall 10:41:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:41:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:41:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:41:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:41:56 INFO - Running setup.py install for mozleak 10:41:57 INFO - Running setup.py install for mozlog 10:41:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:41:57 INFO - Running setup.py install for moznetwork 10:41:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:41:57 INFO - Running setup.py install for mozprocess 10:41:57 INFO - Running setup.py install for mozprofile 10:41:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:41:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:41:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:41:57 INFO - Running setup.py install for mozrunner 10:41:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:41:57 INFO - Running setup.py install for mozscreenshot 10:41:58 INFO - Running setup.py install for moztest 10:41:58 INFO - Running setup.py install for mozversion 10:41:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:41:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:41:58 INFO - Cleaning up... 10:41:58 INFO - Return code: 0 10:41:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:41:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:41:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:41:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:41:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:41:58 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 0x1029a4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a39618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfcb5da690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102908d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102900b20>, '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.JtlgCsAIRx/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.G4v9yvcsid/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 10:41:58 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 10:41:58 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 10:41:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtlgCsAIRx/Render', 10:41:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:41:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:41:58 INFO - 'HOME': '/Users/cltbld', 10:41:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:41:58 INFO - 'LOGNAME': 'cltbld', 10:41:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:41:58 INFO - 'MOZ_NO_REMOTE': '1', 10:41:58 INFO - 'NO_EM_RESTART': '1', 10:41:58 INFO - 'PAGER': '/bin/cat', 10:41:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:41:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:41:58 INFO - 'PWD': '/builds/slave/test', 10:41:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:41:58 INFO - 'SHELL': '/bin/bash', 10:41:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G4v9yvcsid/Listeners', 10:41:58 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 10:41:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:41:58 INFO - 'USER': 'cltbld', 10:41:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:41:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:41:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:41:58 INFO - 'XPC_FLAGS': '0x0', 10:41:58 INFO - 'XPC_SERVICE_NAME': '0', 10:41:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:41:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:41:58 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-asx1zd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:41:58 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)) 10:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:41:58 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-1ojxrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:41:58 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)) 10:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:41:58 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-LF3aPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:41:58 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)) 10:41:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:41:58 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-Y5NbWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:41:59 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)) 10:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:41:59 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-h01keS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:41:59 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)) 10:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:41:59 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-RjxjyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:41:59 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)) 10:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:41:59 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-cOHOYG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:41:59 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)) 10:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:41:59 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-Rz3Xpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:41:59 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)) 10:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:41:59 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-yDG7lo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:41:59 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)) 10:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:41:59 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-kbzvTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:41:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 10:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:41:59 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-1UQJ5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:41:59 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)) 10:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:41:59 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-9IqrBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:41:59 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)) 10:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:41:59 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-RpD_J3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:41:59 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)) 10:41:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:42:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-ZRUisy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:42:00 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)) 10:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:42:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-ac8wQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:42:00 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)) 10:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:42:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-PhlPni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:42:00 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)) 10:42:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:42:00 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-F5VkTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:42:00 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)) 10:42:00 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)) 10:42:00 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)) 10:42:00 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)) 10:42:00 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)) 10:42:00 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)) 10:42:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:42:00 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 10:42:00 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 10:42:00 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 10:42:00 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 10:42:00 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 10:42:00 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 10:42:02 INFO - Downloading blessings-1.6.tar.gz 10:42:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:42:02 INFO - Installing collected packages: blessings 10:42:02 INFO - Running setup.py install for blessings 10:42:03 INFO - Successfully installed blessings 10:42:03 INFO - Cleaning up... 10:42:03 INFO - Return code: 0 10:42:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:42:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:42:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:42:03 INFO - Reading from file tmpfile_stdout 10:42:03 INFO - Current package versions: 10:42:03 INFO - blessings == 1.6 10:42:03 INFO - blobuploader == 1.2.4 10:42:03 INFO - docopt == 0.6.1 10:42:03 INFO - functools32 == 3.2.3-2 10:42:03 INFO - jsonschema == 2.5.1 10:42:03 INFO - manifestparser == 1.1 10:42:03 INFO - mozInstall == 1.12 10:42:03 INFO - mozcrash == 0.17 10:42:03 INFO - mozdebug == 0.1 10:42:03 INFO - mozdevice == 0.48 10:42:03 INFO - mozfile == 1.2 10:42:03 INFO - mozhttpd == 0.7 10:42:03 INFO - mozinfo == 0.9 10:42:03 INFO - mozleak == 0.1 10:42:03 INFO - mozlog == 3.2 10:42:03 INFO - moznetwork == 0.27 10:42:03 INFO - mozprocess == 0.23 10:42:03 INFO - mozprofile == 0.28 10:42:03 INFO - mozrunner == 6.12 10:42:03 INFO - mozscreenshot == 0.1 10:42:03 INFO - mozsystemmonitor == 0.3 10:42:03 INFO - moztest == 0.7 10:42:03 INFO - mozversion == 1.4 10:42:03 INFO - psutil == 3.1.1 10:42:03 INFO - requests == 1.2.3 10:42:03 INFO - wsgiref == 0.1.2 10:42:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:42:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:42:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:42:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:42:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:03 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 0x1029a4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a39618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfcb5da690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102908d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102900b20>, '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.JtlgCsAIRx/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.G4v9yvcsid/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 10:42:03 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 10:42:03 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 10:42:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtlgCsAIRx/Render', 10:42:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:03 INFO - 'HOME': '/Users/cltbld', 10:42:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:03 INFO - 'LOGNAME': 'cltbld', 10:42:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:03 INFO - 'MOZ_NO_REMOTE': '1', 10:42:03 INFO - 'NO_EM_RESTART': '1', 10:42:03 INFO - 'PAGER': '/bin/cat', 10:42:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:03 INFO - 'PWD': '/builds/slave/test', 10:42:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:03 INFO - 'SHELL': '/bin/bash', 10:42:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G4v9yvcsid/Listeners', 10:42:03 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 10:42:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:03 INFO - 'USER': 'cltbld', 10:42:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:03 INFO - 'XPC_FLAGS': '0x0', 10:42:03 INFO - 'XPC_SERVICE_NAME': '0', 10:42:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:42:03 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)) 10:42:03 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)) 10:42:03 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)) 10:42:03 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)) 10:42:03 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)) 10:42:03 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)) 10:42:03 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)) 10:42:03 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)) 10:42:03 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)) 10:42:03 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)) 10:42:03 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)) 10:42:03 INFO - Cleaning up... 10:42:03 INFO - Return code: 0 10:42:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:42:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:42:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:42:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:42:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:42:03 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 0x1029a4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a39618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcfcb5da690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102908d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102900b20>, '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.JtlgCsAIRx/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.G4v9yvcsid/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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 10:42:03 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 10:42:03 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 10:42:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtlgCsAIRx/Render', 10:42:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:03 INFO - 'HOME': '/Users/cltbld', 10:42:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:03 INFO - 'LOGNAME': 'cltbld', 10:42:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:03 INFO - 'MOZ_NO_REMOTE': '1', 10:42:03 INFO - 'NO_EM_RESTART': '1', 10:42:03 INFO - 'PAGER': '/bin/cat', 10:42:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:03 INFO - 'PWD': '/builds/slave/test', 10:42:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:03 INFO - 'SHELL': '/bin/bash', 10:42:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G4v9yvcsid/Listeners', 10:42:03 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 10:42:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:03 INFO - 'USER': 'cltbld', 10:42:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:03 INFO - 'XPC_FLAGS': '0x0', 10:42:03 INFO - 'XPC_SERVICE_NAME': '0', 10:42:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:42:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:42:04 INFO - Cleaning up... 10:42:04 INFO - Return code: 0 10:42:04 INFO - Running post-action listener: _resource_record_post_action 10:42:04 INFO - Running post-action listener: _start_resource_monitoring 10:42:04 INFO - Starting resource monitoring. 10:42:04 INFO - ##### 10:42:04 INFO - ##### Finished create-virtualenv step (success) 10:42:04 INFO - ##### 10:42:04 INFO - ##### 10:42:04 INFO - ##### Running install step. 10:42:04 INFO - ##### 10:42:04 INFO - Running pre-action listener: _resource_record_pre_action 10:42:04 INFO - Running main action method: install 10:42:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:42:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:42:04 INFO - Reading from file tmpfile_stdout 10:42:04 INFO - Detecting whether we're running mozinstall >=1.0... 10:42:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:42:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:42:04 INFO - Reading from file tmpfile_stdout 10:42:04 INFO - Output received: 10:42:04 INFO - Usage: mozinstall [options] installer 10:42:04 INFO - Options: 10:42:04 INFO - -h, --help show this help message and exit 10:42:04 INFO - -d DEST, --destination=DEST 10:42:04 INFO - Directory to install application into. [default: 10:42:04 INFO - "/builds/slave/test"] 10:42:04 INFO - --app=APP Application being installed. [default: firefox] 10:42:04 INFO - mkdir: /builds/slave/test/build/application 10:42:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:42:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:42:39 INFO - Reading from file tmpfile_stdout 10:42:39 INFO - Output received: 10:42:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:42:39 INFO - Running post-action listener: _resource_record_post_action 10:42:39 INFO - ##### 10:42:39 INFO - ##### Finished install step (success) 10:42:39 INFO - ##### 10:42:39 INFO - ##### 10:42:39 INFO - ##### Running run-tests step. 10:42:39 INFO - ##### 10:42:39 INFO - Running pre-action listener: _resource_record_pre_action 10:42:39 INFO - Running main action method: run_tests 10:42:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:42:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:42:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:42:39 INFO - 2016-07-13 10:42:39.198 screenresolution[1637:8321] starting screenresolution argv=screenresolution get 10:42:39 INFO - 2016-07-13 10:42:39.200 screenresolution[1637:8321] Display 0: 1600x1200x32@60 10:42:39 INFO - 2016-07-13 10:42:39.205 screenresolution[1638:8323] starting screenresolution argv=screenresolution list 10:42:39 INFO - Available Modes on Display 0 10:42:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:42:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:42:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:42:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:42:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:42:39 INFO - Intel Iris: 10:42:39 INFO - Chipset Model: Intel Iris 10:42:39 INFO - Type: GPU 10:42:39 INFO - Bus: Built-In 10:42:39 INFO - VRAM (Dynamic, Max): 1536 MB 10:42:39 INFO - Vendor: Intel (0x8086) 10:42:39 INFO - Device ID: 0x0a2e 10:42:39 INFO - Revision ID: 0x0009 10:42:39 INFO - Displays: 10:42:39 INFO - Display: 10:42:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:42:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:42:39 INFO - Display Serial Number: 1600x1200 60 10:42:39 INFO - Main Display: Yes 10:42:39 INFO - Mirror: Off 10:42:39 INFO - Online: Yes 10:42:39 INFO - Rotation: Supported 10:42:39 INFO - Return code: 0 10:42:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:42:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:42:39 INFO - Python 2.7.3 10:42:39 INFO - Return code: 0 10:42:39 INFO - grabbing minidump binary from tooltool 10:42:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:42:39 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 0x102908d30>, '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 0x10266ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102900b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:42:39 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 10:42:39 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 10:42:39 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 10:42:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:42:39 INFO - Return code: 0 10:42:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:42:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:42:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:42:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:42:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:42:39 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/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--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 10:42:39 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/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0226 --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 10:42:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JtlgCsAIRx/Render', 10:42:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:42:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:42:39 INFO - 'HOME': '/Users/cltbld', 10:42:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:42:39 INFO - 'LOGNAME': 'cltbld', 10:42:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:42:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:42:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:42:39 INFO - 'MOZ_NO_REMOTE': '1', 10:42:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:42:39 INFO - 'NO_EM_RESTART': '1', 10:42:39 INFO - 'PAGER': '/bin/cat', 10:42:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:42:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:42:39 INFO - 'PWD': '/builds/slave/test', 10:42:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:42:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:42:39 INFO - 'SHELL': '/bin/bash', 10:42:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G4v9yvcsid/Listeners', 10:42:39 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 10:42:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:42:39 INFO - 'USER': 'cltbld', 10:42:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:42:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:42:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:42:39 INFO - 'XPC_FLAGS': '0x0', 10:42:39 INFO - 'XPC_SERVICE_NAME': '0', 10:42:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:42:39 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/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--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 10:42:39 INFO - mozversion application_buildid: 20160713082525 10:42:39 INFO - mozversion application_changeset: 81bd8453740f30844982f13ca739ce960889d208 10:42:39 INFO - mozversion application_display_name: Nightly 10:42:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:42:39 INFO - mozversion application_name: Firefox 10:42:39 INFO - mozversion application_remotingname: firefox 10:42:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:42:39 INFO - mozversion application_vendor: Mozilla 10:42:39 INFO - mozversion application_version: 50.0a1 10:42:39 INFO - mozversion platform_buildid: 20160713082525 10:42:39 INFO - mozversion platform_changeset: 81bd8453740f30844982f13ca739ce960889d208 10:42:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:42:39 INFO - mozversion platform_version: 50.0a1 10:42:39 INFO - using testdate: 1468431759 10:42:39 INFO - actual date: 1468431759 10:42:39 INFO - starting webserver on 'localhost:49267' 10:42:39 INFO - SUITE-START | Running 2 tests 10:42:39 INFO - TEST-START | dromaeo_css 10:42:39 INFO - operating with platform_type : mac_ 10:42:39 INFO - Initialising browser for dromaeo_css test... 10:42:39 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpqlv1bi/profile http://localhost:49267/getInfo.html) 10:42:42 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 10:42:42 INFO - PROCESS | 1648 | colorDepth:24 10:42:42 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 10:42:42 INFO - PROCESS | 1648 | __metrics 10:42:42 INFO - PROCESS | 1648 | 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" 10:42:42 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:42:43 INFO - TEST-INFO | 1648: exit 0 10:42:43 INFO - Browser initialized. 10:42:43 INFO - Running cycle 1/1 for dromaeo_css test... 10:42:43 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpqlv1bi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:42:55 INFO - PROCESS | 1651 | RSS: Main: 243757056 10:42:55 INFO - PROCESS | 1651 | 10:43:46 INFO - PROCESS | 1651 | 1468431826294 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] 10:45:57 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 10:45:58 INFO - PROCESS | 1651 | RSS: Main: 395427840 10:45:58 INFO - PROCESS | 1651 | 10:49:02 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 10:49:03 INFO - PROCESS | 1651 | RSS: Main: 370348032 10:49:03 INFO - PROCESS | 1651 | 10:52:05 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 10:52:05 INFO - PROCESS | 1651 | RSS: Main: 376102912 10:52:05 INFO - PROCESS | 1651 | 10:55:08 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 10:55:08 INFO - PROCESS | 1651 | RSS: Main: 372412416 10:55:08 INFO - PROCESS | 1651 | 10:58:12 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 10:58:12 INFO - PROCESS | 1651 | RSS: Main: 343924736 10:58:12 INFO - PROCESS | 1651 | 11:01:15 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 11:01:16 INFO - PROCESS | 1651 | RSS: Main: 401027072 11:01:16 INFO - PROCESS | 1651 | 11:01:16 INFO - PROCESS | 1651 | __start_tp_report 11:01:16 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 11:01:16 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 11:01:16 INFO - PROCESS | 1651 | |i|pagename|runs| 11:01:16 INFO - PROCESS | 1651 | |0;dojo.html;3345.5818022747158;3350.6649335066495;3369.679880411361;3377.915552111195;3427.828608569571;5619.5785316101355;5650.660960342378;5665.405132461094;5764.769409223636;5790.565707571928;5663.122216056508;5713.400092990228;5777.797777077803;5803.390643982374;5835.095560188167;4342.782860856961;5096.949030509703;5119.206522988924;5135.178371460567;5284.762185701634;5288.576913846883;5291.444398338177;5309.575233981292;5343.278657381262;5386.757595908193;5139.58883289336;5182.922256166161;5195.584353251731;5216.556592689626;5231.686098834063;5221.1124108901195;5247.606429517763;5249.396319423277;5252.973735131338;5253.264542963988;5052.216906379517;5116.692998420107;5149;5208.8958220835375;5239.187925871496;5624.634398764068;5637.605367624268;5713.857153571153;5748.683822389767;5794.478496935296;6192.442680158807;6242.500599951993;6251.9687401562705;6255.655938923357;6257.3116957135135;6119.746288422662;6184.381561843779;6210.410011048988;6276.592021518587;6298.811035668938;5842.444967728059;5858.443447872445;5860.472557469763;5862.443067908542;5872.324682661463;6240.719167637467;6315.621062736251;6317.778877739349;6321.336259692666;6321.683915804191;6088.238970128734;6106.582013105394;6142.04798256271;6166.81499555014;6183.288921773964;6279.560430769802;6298.307186209513;6298.464630506458;6306.590071645328;6311.526635502356;6030.33666296707;6087.482563982023;6213.720382582795;6247.0629405589525;6254.906176407358;6153.538484613582;6192.59748116371;6202.472789812917;6210.254769427607;6271.341509141565;4941.234108713155;4956.033573453142;4958.107540642719;4964.205727083649;5002.699838009731;4422.668299877522;4439.334099884978;4477.6417886569;4478.283474644041;4502.099580083996;5647.802326918621;5666.263385759825;5681.573881958787;5711.914321285184;5802.564807639444;6289.652427039552;6299.370062993664;6306.936930630635;6307;6310.148130002391;5529.529989950819;5715.2855893013375;5724.313082430135;5749.453801888814;5775.6823374714795;5346.1713434417725;5346.278252435949;5387.0676451659765;5422.566194704415;5443.673379597237;6282.434580887742;6302.1492098566105;6305.873882522324;6313.3686631336495;6319.083732858618;6116.609690437491;6256.061590761422;6313.147725057059;6334.1765570475545;6351.237851457855;6223.471004964629;6272.8431789203805;6296.5592408530965;6313.936860631335;6318.494520438446;6186.288576813724;6194.38056194377;6210.4100110489435;6214.658193799203;6234.937650623436;6100.096771792299;6191.504679625529;6226.346233645402;6255.906161407492;6333.240011198685;5195.480451954774;6103.097294528758;6130.5402094842175;6135.539834512519;6153.8307721532765;6102.115193297036;6132.41742034506;6134.601250918676;6139.079138129317;6151.876962460637;5304.257403963526;5799.739011744397;5844.386339434298;5955.166276721176;5987.820365389045;5514.310711161105;6051.697415129138;6058.394160583906;6098.810731907235;6131.110988906673;5939.673317967553;5965.135055417027;5977.521798256216;5978.790742323998;6006.309274433496 11:01:16 INFO - PROCESS | 1651 | |1;ext.html;492.261607588617;517.0899217377416;537.6209772110662;540.3893600231737;549.2447884159282;29863.253418664517;41203;41238;41321.58678413215;41339.37990930131;43739.78130109345;43992.900177495576;44028;44087;44160.558394416046;8673.783155421133;9613.279004074704;9796.363236389647;9797.657082002132;9834.55744491496;8552.78618034548;11716.589919352824;11871.703207419798;11905.345206013666;12084.516619335216;11600.187986840925;11809.586664466746;11840.703982400466;11879.406029698524;11916.821247681291;8630.741077767678;8811.339149563744;8840.469571825646;11844.7631047379;11941.58204462844;8751.78120546985;11405.771884562346;11642.476088576035;11673.241239319417;11943.880561194364;41799.95500112491;41937.79031104825;42076;42157.78921105375;42194.57805421968;3528.904612548864;3556.466530020492;3604.7296452766145;3641.18073433478;4272.6795490338345;31386.843065784753;32756.508652370234;32793;32987.6701232987;33085.503717444255;3316.4859446785786;3327.101682545699;3378.6114596821435;3400.8129552874584;3441.2601290722614;534061.9999999962;534076;534233;534378;534912;33805.32389352199;34034.65965340315;61991.690041549504;62371.376286237464;62458.37541624525;36054.639453605654;36188.914332570064;36215;36341.454878176846;36347.09132271714;15036.548903532912;15122.54632361031;15197.088174709554;15208.771868421982;15231.238438078051;11474.885251147549;11621.360825154528;12552.807483289072;12708.428120734588;12751.107422480338;6682.866342673119;7905.920940790634;7976.52140871538;7976.84046319082;7979.401544884041;3235.9838200808845;3274.9017529474154;3284.5730055093163;3308.669133086672;3309.2057906102364;1005.9949700251452;1011.4891979550428;1027.075631931253;1069.5454431866426;1070.5557193764657;31096.378072438878;31119.066428007198;31229.843850781053;31982.880599178916;32067;1014.2697257974247;1102.6030628973563;1140.589387820384;1196.4017991004498;1252.3988485526816;16114.677706445806;16285.511434656979;16366.345346186019;16379;16847;717872;765699.17150415;766294;767929;1064268;61689;61705;62424.63027588116;62432;62504.06243906345;1043.7025447747508;1248.4694005047636;1410.4922227997913;1432.1335591966917;1452.3609611770999;28102.437951240452;29651.703482964895;32871.67128328686;33346.499802503466;33351;48071.5192848067;58462.830743384045;58552.707236463546;58638.82722345444;58873.11690324734;662888;670399;675110;679962;684056;24181.51636967286;24290.87854560716;26646.733532664424;27910.44179116447;27993.300167495974;38382;39919;41997.580024199364;46951.765241173576;51898;39455.60544394519;40426;41440;41500.962475937136;45390.865228369556;41824.581754182065;43309.35035974526;44351.66944991655;44953;45701.54298456973;41169.1766164669;42071.7896410516;42114.57885421229;42650.1469970605;43080.78459607682;44084.779576101915;44161.558384415745;44267.11465770731;44986;45019.77490112528;22596;27885.141385106475;28049.43525294279;28159.763638451197;28201.41152008327 11:01:16 INFO - PROCESS | 1651 | |2;jquery.html;327.4744035822505;378.17557724161327;389.04295433234245;390.2409812913882;407.0495393256745;20107.29624463144;20548.280810171647;20831.687524687124;21541.24605638803;21591;20362.490937726565;20810.375688729324;21117.366479005617;21471.248506302283;21517.17104046158;5527.917081243784;5594.272744543214;5755.9927012772805;6019.879602407949;6052.485538729214;5964.940350596504;6003;6055.9394406056035;6057.30341010785;6062.4543791058795;5273.288106105665;5907.911381329283;5912.674802885861;5935.465808077272;5951.702414879239;5680.488756011979;5866.237389139427;5887.175795388649;5965.075413310966;5972.0444728843395;5086.186210206386;5112.130937740593;5118.027574760784;5129.333186685744;5134.973005398935;25358.619620705704;25440.745592544023;25502;25720.485590288277;25761.871190643928;2213.3581261434165;2228.3092241405216;2417.4319035026756;2613.777828884547;2984.867002513897;15701.214939253106;15723.292451839694;15830.734299758002;16465.25906334218;16504.83495165057;1813.292815801825;1906.323255244396;1975.7233987241798;2002.5594369238745;2007.8393728501685;76404.61797691032;101168.49415752874;102825;107977;111697.44151279192;10714.46427678613;10833.104206763785;10873.891261087289;10917.727056823484;11049.28179668319;12192.329421881881;12646.304453254976;13308.070329802125;13350.732985340434;13353.73292534144;7942.166072562298;7956.164602716747;8146.714864979697;8425.95787021061;8616.009158946676;8032.357411407073;8675;8682.86975695365;8766.167214114648;8800.603972821238;5744.339400968858;5985.191999080069;6284.9001424751405;6584.539082264196;6605.900911486331;1545.4204673247532;1820;1847.5658220318214;1848.3807924345226;1848.6949653307322;874.61079819481;915.8763566918514;967.2117224462041;1005.6580762540625;1030.8402197659375;22333.330000100024;22376.875265220668;22653.886730566242;22893.771062289165;22898;830.3979614779237;935.3817895041481;945.6879229854131;953.4517652349788;964.5514835601339;9450.480223587769;10502.002309780559;11726.70683232926;11882.28706277626;11895.524179032915;580804;584668;595871;598631;599073;100579.49710251403;120945;122110.38944805218;125157;127068.36465817796;1009.2682804966487;1017.0592202212923;1031.5409642708923;1041.2138835179392;1053.4100903494068;17135.48593542196;19853.106610202285;19868.304609339182;19882.204711811366;19931.701024484355;51379.74310128426;52288.47711522836;53559;54366.91266174575;55575;51379;71450.2137446568;71507.28492715214;71868.64065679639;72928.63535682288;7845.137034926268;7862.410319225966;8012.919870801451;8041.356691464785;8259.174082591693;11473.967342938842;11854.762904741685;12626.684332891751;12771.297578632893;12786.296753678636;11223.15826313046;11391.772164556496;11432.885671143182;11471.483783229942;11491.310521368745;8619.569021549023;10934.507947142556;11142.554297827995;11242.606508772154;11262.831057534308;11378;11390.943045284721;11441.599544015919;11492.770144597229;11665.24175928562;7864.488808227676;7911.406644501571;7921.841563168819;8091.635876385694;8207.466514676766;11112.277701949348;11147.386893720599;11286.49210785533;11287.492062857027;11414.828777568177 11:01:16 INFO - PROCESS | 1651 | |3;mootools.html;1365.9521916732904;1500.1224283793981;1527.7555591105424;1576.984230157699;1723.138430784607;7737.41969352298;8015.839683206333;8017.278444939954;8022.478538894975;8026.1973802619705;8342.416030877841;8597.656093756243;8629.741107766762;8696.782580435492;8703.390762646617;2136.583366243583;2351.9412014699683;2353;2354;2361.5749165150264;1907.4563749331444;2332.47519308156;2348.471593891369;2350.9529809403803;2351.6119840226343;2299.701038864945;2322.291701031185;2330.8601483910934;2336.9532609347802;2339.1812865497027;2307.492351682627;2330.4406942333803;2332.242021343062;2332.953340933172;2340.625499920022;2321.141177764221;2329.312852708455;2332.486852892378;2333.778291062363;2333.8482998605205;8687.218150366498;8801.559922003938;8820.2061814437;8821.647134114628;8833;5122.154844450662;5125.692458452505;5286.814961476368;5286.969041036999;5295.152775555931;3508.736844736655;3529.858805647771;3597.478365636968;3657.4330978698345;3680.0799800049986;1017.9847302290472;1019.3934608907688;1037.247995203479;1050.369778133114;1102.8731695855;11081.168912331608;11092.55629774816;11102.444877756161;11369.829452558219;11380.54477820878;4546.636269098464;4636.768161591907;4760.833370832004;4763.237881938873;4766.261229509431;9902.900970990198;9917.355371900947;9918.80162396748;9933.503324833873;9941.105300522842;5337.172738225504;5345.278387417727;5346.251524786533;5348.812791552277;5352.2506849041165;4381.6494680425485;4537.863864084083;4743.644226683013;4874.098291816025;4900.926486102711;3882.8835134946;3896.376579747227;3983.203359328146;4025.597440255951;4046.4132700758223;1072.1826504075673;1129.8983091521638;1197.8442802435627;1285.8135570342247;1289.548657969703;624.3288464900251;774.4540099230029;818.382121498265;860.9827803443897;982.5087456271864;5519.779208831602;5799.333076696234;5818.127280907897;5843.795467158629;6011.819645410645;907.2696479334068;921.5899673499556;940.4780346907532;943.2501161576563;961.4904100826573;3784.413415920537;3887.067103895045;3913.726039177287;3959;3994.2211268802303;10242.283040187265;10508.842367364496;10665.306755060896;10681.946590266998;10699.679009629723;10535.525901334458;10599.311044782064;10601.787964240671;10639.361638301727;10663.4135122569;7896.407769417317;8118.5128892265475;8200.31893461827;8221.588920554068;8232.711855085043;5654.915176272441;5753.280839895013;5775.5090817281;5780.624259423124;5780.884382312414;9686.370385924893;10109.545070471699;10116.494175291353;10117.696469105938;10119.79760404773;8636.78408039804;9780.657676981271;10152.796944061225;10176.236782241214;10273.691789246335;7322.121345438582;7323.890141647986;7403.925960740324;7447.218042105501;7494.475386722877;8086.393520485869;8134.552599607078;8178.83642327162;8194.713185038496;8196.67213311461;5873.4126587340925;6881.621510816954;7278.417726581755;7279.8544029120185;7565.4325925556905;7077.5753454792875;7253.419726421979;7278.781636550912;7339.266073392618;7392.260773922565;6925.861482770417;6983.720651173896;7036.226015138228;7048.154221493453;7051.541649792747;4576.519465456212;4786.497417771223;4789.473157952692;4861.951380486292;4919.262110683426;4693.812247510062;4719.7404142772475;4858.17411040117;4868.367112275381;4998.750062496788 11:01:16 INFO - PROCESS | 1651 | |4;prototype.html;294.7244326554671;306.4851050235605;310.5915720827113;311.9844007799611;313.9874405023799;4622.283546050359;5278.498542638448;5309.8938021239555;5342.679439233644;5376.381716102649;5258.815941442051;5265.236540701596;5290.206469029647;5306.8407947761625;5315.441878602749;3629.782213067211;3804.790736509498;3870.6903447724117;3941.605839416064;4005.0387906902424;3383.4924761285756;3502.4396096624546;3689.981550092246;3940.921181576367;4082.2243773682903;3281.3273278977754;4007.639312461878;4032.6370626643597;4050.9392359114636;4061.3501839705655;3700.0379901225606;3728.6644202021675;3819.2361527694434;3968.305546529346;4011.859584914514;3482.6517348265224;3484.6341134181052;3516.982415087934;3526.312369088029;3539.94690079649;5099.863583895914;5104.82589004531;5130.410002849682;5137.073597400975;5167.3282473278605;3652.4886515887797;4533.229351010336;4598.609118224955;4599.678022538424;4632.606228470584;4770.0698363819065;4867.831720387082;5324.440933701944;5468.316460442445;5558.749856256478;3898.688104951597;3927.1163988102735;3929.8624548140965;3937.0551067743822;3939;4637.605803506706;4870.001649661811;5469.890602187934;5797.043487824504;5843.831233753224;3310.817905015247;3534.575850897909;3978.8806335809973;4020;4024.1549274652066;3309.4539400998565;3983.183447393277;4064.9390259146135;4071.4096456014304;4087.9780054986254;3968.2261958917734;4578.244589642738;4615.169269531517;4663.813447462131;4668;4849.830255941096;4874.975625121851;5206.557442617344;5544.611877168641;5606.635568688022;1215.4166000319897;1332.1407692038774;1337.7057047449546;1383.3774801339437;1396.4902810474105;754.7660225330166;831.5177197225595;857.5926434943477;869.4392117084445;877.8156587116777;5040.54635082837;5044.646874718808;5072.568831649277;5089.465606111379;5089.567386772092;4821.662483626183;5467.824417750203;5625.353084395347;5648.943510564924;5689.146628005832;4400.75795831226;4570.794314255866;5047.9495205048215;5122.718250496258;5150.793968241303;2600.7386417587454;2766.243435418524;2986.0590616099707;2992.7007299270335;2995.5356919677483;5124.898146898435;5227.3465816772905;5679.046895614258;5894.528437724972;5917.615355001911;5619.831405057855;5649.717514124277;5749.453801888898;5795.507381872588;5833.433289352672;5731.570132240098;5754.223179870663;5757.9424205757405;5795.101759227411;5838.970805145947;5406.999705054577;5698.344690360662;5705.572082093777;5717.799877004285;5750.424957504216;5192.766325515437;5245.747636465416;5300.091966891993;5524.613277070566;5543.61194716366;5753.597248192586;5779.566532509994;5844.912326315024;5867.647941123546;5886.440788125121;3641.126129728899;3685.6583479963533;3865.8260378282484;3881.4177873319227;3888.5333759948985;4958.355413796184;5547.556195504269;5637.661740295595;5647.124695672177;5657.660540367591;5468.5351745100525;5502.367227768857;5519.613627046068;5522.751476183662;5527.844140785017;4682.719036857799;4683.742394168354;4832.468428472936;5189.612876794742;5556.972215138899;5341.145416733304;5388.515033646991;5390.326209223847;5409.810656626999;5413.918791218056;4538.65960053004;5181.3782346118705;5408.75660595282;5445.603934268909;5512.972435137799;4819.554133759788;5059.494050594911;5435.130379139318;5440.727963601883;5501.559875209893 11:01:16 INFO - PROCESS | 1651 | |5;yui.html;753.8906858505517;778.179298054552;800.8998875140608;813.324940299551;863.5768473448011;14890.553283401501;16423.34306627735;16456.25946832394;16481.175941202953;16508.669826603462;14664.926675366609;14918.627034324147;14944;14950.10299382038;14994.250287485636;665.9766908158227;685.8971154326841;686.7905288886888;694.4687314204638;697.6476879176034;826.1944604011101;827.9130691277419;841.1252297610508;865.6018231613434;868.452874688945;890.1765866573426;904.9638164301871;905.4069584422214;905.4522014181424;906.0169715858302;708.3872450330479;897.0715309654541;897.5018864530183;899.9190072893439;900.5632268349846;633.6198281031344;634.2452481546944;654.4764188649109;675.9695813688396;679.37497502298;15343.619074283257;15421.997570157904;15581.688366232613;15586.532404027897;15644.061356318658;14223;14378.352974116086;14478.131312121306;14574.344154513072;14607.123572585679;13384.19694818314;13535.255560944144;13658.931705341509;13852.307384630729;13916.443342266297;4663.720176789369;4733.857984260478;4817.51824817519;4819.373481447389;4923.040007198598;116893.83106168914;117203;117328.24007639807;117486;117836;11256.887431125748;11392.25950313227;11437.656870293904;11441.313521188755;11491.138164637685;23055;23090.769092309198;23104.76895231026;23155.768442315697;23309.300720978397;7942.205779422012;7963.482373645695;8029.638666260033;8196.467229630054;8232.300254478316;4403.229434848889;4458.242098843204;4459.955400445954;4506.974723183692;4563.041761230113;3525.4888041233876;3673.210259794157;3673.393890008172;3682.226732386229;3683.723720720957;646.3859333633068;659.5119611487464;661.5203977116552;670.0418401685539;675.0650349266301;567.5743192605546;599.4485073732177;622.6139793328166;632.0456111272013;633.740166531729;8448.23965843077;8782.180389654395;8919.559068967925;9034.86447703285;9035.232005279624;511.7211119939642;581.7411251992822;586.3608666553478;618.0103034677809;632.7880160146402;3690.4371013800624;4279.97860010698;4324.777621372253;4390.0708865565775;4398.120375924764;131437;134115;147800;150650.24674876555;159092.20453897657;97303.13484325897;120878.3956080214;121318;123049.38475307566;129850;74321.6357150296;75195;76103.85844212452;108405;110592;9768.413895166314;9774.413535187912;9790.657326993522;9892.208623310262;9912.35569687968;63830;64954.67522662356;65543.77334153248;66258.33741662714;68112;62793;70626;83908.58045709733;92428.53785731297;96735.51632241794;10586.311889727143;13151.21092734439;13415.798763018367;13598.592042238748;13635.522756703467;13418.463261469431;14209.644758881112;14243.145411275356;14309.35607897668;14336.354864031353;12838.614841554769;13338.399772010476;13359.198448093146;13372.598822035354;13781.655458613615;13093;13337.368345330864;13632.659183520094;13986.650333741738;14103.647408814863;11482.425878706199;13025.153365031243;13454.865451345753;13575.52485662997;13687.905372006195;11378.943105284421;11513.13651476126;11646.184767066225;11657.941710291394;11843.467043983213;11436.142289328167;11490.88509114898;11536.82694759596;11602.245854019462;11765 11:01:16 INFO - PROCESS | 1651 | __end_tp_report 11:01:16 INFO - PROCESS | 1651 | __start_cc_report 11:01:16 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1587 11:01:16 INFO - PROCESS | 1651 | __end_cc_report 11:01:16 INFO - PROCESS | 1651 | __startTimestamp1468432876075__endTimestamp 11:01:16 INFO - PROCESS | 1651 | 11:01:16 INFO - PROCESS | 1651 | ------- Summary: start ------- 11:01:16 INFO - PROCESS | 1651 | Number of tests: 6 11:01:16 INFO - PROCESS | 1651 | 11:01:16 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5742.08 Median:5965.14 stddev:645.22 (10.8%) stddev-sans-first:619.21 11:01:16 INFO - PROCESS | 1651 | Values: 3345.6 3350.7 3369.7 3377.9 3427.8 5619.6 5650.7 5665.4 5764.8 5790.6 5663.1 5713.4 5777.8 5803.4 5835.1 4342.8 5096.9 5119.2 5135.2 5284.8 5288.6 5291.4 5309.6 5343.3 5386.8 5139.6 5182.9 5195.6 5216.6 5231.7 5221.1 5247.6 5249.4 5253.0 5253.3 5052.2 5116.7 5149.0 5208.9 5239.2 5624.6 5637.6 5713.9 5748.7 5794.5 6192.4 6242.5 6252.0 6255.7 6257.3 6119.7 6184.4 6210.4 6276.6 6298.8 5842.4 5858.4 5860.5 5862.4 5872.3 6240.7 6315.6 6317.8 6321.3 6321.7 6088.2 6106.6 6142.0 6166.8 6183.3 6279.6 6298.3 6298.5 6306.6 6311.5 6030.3 6087.5 6213.7 6247.1 6254.9 6153.5 6192.6 6202.5 6210.3 6271.3 4941.2 4956.0 4958.1 4964.2 5002.7 4422.7 4439.3 4477.6 4478.3 4502.1 5647.8 5666.3 5681.6 5711.9 5802.6 6289.7 6299.4 6306.9 6307.0 6310.1 5529.5 5715.3 5724.3 5749.5 5775.7 5346.2 5346.3 5387.1 5422.6 5443.7 6282.4 6302.1 6305.9 6313.4 6319.1 6116.6 6256.1 6313.1 6334.2 6351.2 6223.5 6272.8 6296.6 6313.9 6318.5 6186.3 6194.4 6210.4 6214.7 6234.9 6100.1 6191.5 6226.3 6255.9 6333.2 5195.5 6103.1 6130.5 6135.5 6153.8 6102.1 6132.4 6134.6 6139.1 6151.9 5304.3 5799.7 5844.4 5955.2 5987.8 5514.3 6051.7 6058.4 6098.8 6131.1 5939.7 5965.1 5977.5 5978.8 6006.3 11:01:16 INFO - PROCESS | 1651 | 11:01:16 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:78906.03 Median:36344.27 stddev:185373.63 (510.0%) stddev-sans-first:185800.16 11:01:16 INFO - PROCESS | 1651 | Values: 492.3 517.1 537.6 540.4 549.2 29863.3 41203.0 41238.0 41321.6 41339.4 43739.8 43992.9 44028.0 44087.0 44160.6 8673.8 9613.3 9796.4 9797.7 9834.6 8552.8 11716.6 11871.7 11905.3 12084.5 11600.2 11809.6 11840.7 11879.4 11916.8 8630.7 8811.3 8840.5 11844.8 11941.6 8751.8 11405.8 11642.5 11673.2 11943.9 41800.0 41937.8 42076.0 42157.8 42194.6 3528.9 3556.5 3604.7 3641.2 4272.7 31386.8 32756.5 32793.0 32987.7 33085.5 3316.5 3327.1 3378.6 3400.8 3441.3 534062.0 534076.0 534233.0 534378.0 534912.0 33805.3 34034.7 61991.7 62371.4 62458.4 36054.6 36188.9 36215.0 36341.5 36347.1 15036.5 15122.5 15197.1 15208.8 15231.2 11474.9 11621.4 12552.8 12708.4 12751.1 6682.9 7905.9 7976.5 7976.8 7979.4 3236.0 3274.9 3284.6 3308.7 3309.2 1006.0 1011.5 1027.1 1069.5 1070.6 31096.4 31119.1 31229.8 31982.9 32067.0 1014.3 1102.6 1140.6 1196.4 1252.4 16114.7 16285.5 16366.3 16379.0 16847.0 717872.0 765699.2 766294.0 767929.0 1064268.0 61689.0 61705.0 62424.6 62432.0 62504.1 1043.7 1248.5 1410.5 1432.1 1452.4 28102.4 29651.7 32871.7 33346.5 33351.0 48071.5 58462.8 58552.7 58638.8 58873.1 662888.0 670399.0 675110.0 679962.0 684056.0 24181.5 24290.9 26646.7 27910.4 27993.3 38382.0 39919.0 41997.6 46951.8 51898.0 39455.6 40426.0 41440.0 41501.0 45390.9 41824.6 43309.4 44351.7 44953.0 45701.5 41169.2 42071.8 42114.6 42650.1 43080.8 44084.8 44161.6 44267.1 44986.0 45019.8 22596.0 27885.1 28049.4 28159.8 28201.4 11:01:16 INFO - PROCESS | 1651 | 11:01:16 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:34082.73 Median:12657.72 stddev:98122.88 (775.2%) stddev-sans-first:98365.41 11:01:16 INFO - PROCESS | 1651 | Values: 327.5 378.2 389.0 390.2 407.0 20107.3 20548.3 20831.7 21541.2 21591.0 20362.5 20810.4 21117.4 21471.2 21517.2 5527.9 5594.3 5756.0 6019.9 6052.5 5964.9 6003.0 6055.9 6057.3 6062.5 5273.3 5907.9 5912.7 5935.5 5951.7 5680.5 5866.2 5887.2 5965.1 5972.0 5086.2 5112.1 5118.0 5129.3 5135.0 25358.6 25440.7 25502.0 25720.5 25761.9 2213.4 2228.3 2417.4 2613.8 2984.9 15701.2 15723.3 15830.7 16465.3 16504.8 1813.3 1906.3 1975.7 2002.6 2007.8 76404.6 101168.5 102825.0 107977.0 111697.4 10714.5 10833.1 10873.9 10917.7 11049.3 12192.3 12646.3 13308.1 13350.7 13353.7 7942.2 7956.2 8146.7 8426.0 8616.0 8032.4 8675.0 8682.9 8766.2 8800.6 5744.3 5985.2 6284.9 6584.5 6605.9 1545.4 1820.0 1847.6 1848.4 1848.7 874.6 915.9 967.2 1005.7 1030.8 22333.3 22376.9 22653.9 22893.8 22898.0 830.4 935.4 945.7 953.5 964.6 9450.5 10502.0 11726.7 11882.3 11895.5 580804.0 584668.0 595871.0 598631.0 599073.0 100579.5 120945.0 122110.4 125157.0 127068.4 1009.3 1017.1 1031.5 1041.2 1053.4 17135.5 19853.1 19868.3 19882.2 19931.7 51379.7 52288.5 53559.0 54366.9 55575.0 51379.0 71450.2 71507.3 71868.6 72928.6 7845.1 7862.4 8012.9 8041.4 8259.2 11474.0 11854.8 12626.7 12771.3 12786.3 11223.2 11391.8 11432.9 11471.5 11491.3 8619.6 10934.5 11142.6 11242.6 11262.8 11378.0 11390.9 11441.6 11492.8 11665.2 7864.5 7911.4 7921.8 8091.6 8207.5 11112.3 11147.4 11286.5 11287.5 11414.8 11:01:16 INFO - PROCESS | 1651 | 11:01:16 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5613.54 Median:4860.06 stddev:3144.11 (64.7%) stddev-sans-first:3136.73 11:01:16 INFO - PROCESS | 1651 | Values: 1366.0 1500.1 1527.8 1577.0 1723.1 7737.4 8015.8 8017.3 8022.5 8026.2 8342.4 8597.7 8629.7 8696.8 8703.4 2136.6 2351.9 2353.0 2354.0 2361.6 1907.5 2332.5 2348.5 2351.0 2351.6 2299.7 2322.3 2330.9 2337.0 2339.2 2307.5 2330.4 2332.2 2333.0 2340.6 2321.1 2329.3 2332.5 2333.8 2333.8 8687.2 8801.6 8820.2 8821.6 8833.0 5122.2 5125.7 5286.8 5287.0 5295.2 3508.7 3529.9 3597.5 3657.4 3680.1 1018.0 1019.4 1037.2 1050.4 1102.9 11081.2 11092.6 11102.4 11369.8 11380.5 4546.6 4636.8 4760.8 4763.2 4766.3 9902.9 9917.4 9918.8 9933.5 9941.1 5337.2 5345.3 5346.3 5348.8 5352.3 4381.6 4537.9 4743.6 4874.1 4900.9 3882.9 3896.4 3983.2 4025.6 4046.4 1072.2 1129.9 1197.8 1285.8 1289.5 624.3 774.5 818.4 861.0 982.5 5519.8 5799.3 5818.1 5843.8 6011.8 907.3 921.6 940.5 943.3 961.5 3784.4 3887.1 3913.7 3959.0 3994.2 10242.3 10508.8 10665.3 10681.9 10699.7 10535.5 10599.3 10601.8 10639.4 10663.4 7896.4 8118.5 8200.3 8221.6 8232.7 5654.9 5753.3 5775.5 5780.6 5780.9 9686.4 10109.5 10116.5 10117.7 10119.8 8636.8 9780.7 10152.8 10176.2 10273.7 7322.1 7323.9 7403.9 7447.2 7494.5 8086.4 8134.6 8178.8 8194.7 8196.7 5873.4 6881.6 7278.4 7279.9 7565.4 7077.6 7253.4 7278.8 7339.3 7392.3 6925.9 6983.7 7036.2 7048.2 7051.5 4576.5 4786.5 4789.5 4862.0 4919.3 4693.8 4719.7 4858.2 4868.4 4998.8 11:01:16 INFO - PROCESS | 1651 | 11:01:16 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4470.43 Median:5081.02 stddev:1366.21 (26.9%) stddev-sans-first:1333.61 11:01:16 INFO - PROCESS | 1651 | Values: 294.7 306.5 310.6 312.0 314.0 4622.3 5278.5 5309.9 5342.7 5376.4 5258.8 5265.2 5290.2 5306.8 5315.4 3629.8 3804.8 3870.7 3941.6 4005.0 3383.5 3502.4 3690.0 3940.9 4082.2 3281.3 4007.6 4032.6 4050.9 4061.4 3700.0 3728.7 3819.2 3968.3 4011.9 3482.7 3484.6 3517.0 3526.3 3539.9 5099.9 5104.8 5130.4 5137.1 5167.3 3652.5 4533.2 4598.6 4599.7 4632.6 4770.1 4867.8 5324.4 5468.3 5558.7 3898.7 3927.1 3929.9 3937.1 3939.0 4637.6 4870.0 5469.9 5797.0 5843.8 3310.8 3534.6 3978.9 4020.0 4024.2 3309.5 3983.2 4064.9 4071.4 4088.0 3968.2 4578.2 4615.2 4663.8 4668.0 4849.8 4875.0 5206.6 5544.6 5606.6 1215.4 1332.1 1337.7 1383.4 1396.5 754.8 831.5 857.6 869.4 877.8 5040.5 5044.6 5072.6 5089.5 5089.6 4821.7 5467.8 5625.4 5648.9 5689.1 4400.8 4570.8 5047.9 5122.7 5150.8 2600.7 2766.2 2986.1 2992.7 2995.5 5124.9 5227.3 5679.0 5894.5 5917.6 5619.8 5649.7 5749.5 5795.5 5833.4 5731.6 5754.2 5757.9 5795.1 5839.0 5407.0 5698.3 5705.6 5717.8 5750.4 5192.8 5245.7 5300.1 5524.6 5543.6 5753.6 5779.6 5844.9 5867.6 5886.4 3641.1 3685.7 3865.8 3881.4 3888.5 4958.4 5547.6 5637.7 5647.1 5657.7 5468.5 5502.4 5519.6 5522.8 5527.8 4682.7 4683.7 4832.5 5189.6 5557.0 5341.1 5388.5 5390.3 5409.8 5413.9 4538.7 5181.4 5408.8 5445.6 5513.0 4819.6 5059.5 5435.1 5440.7 5501.6 11:01:16 INFO - PROCESS | 1651 | 11:01:16 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:24063.89 Median:3687.08 stddev:37621.21 (1020.4%) stddev-sans-first:37686.03 11:01:16 INFO - PROCESS | 1651 | Values: 753.9 778.2 800.9 813.3 863.6 14890.6 16423.3 16456.3 16481.2 16508.7 14664.9 14918.6 14944.0 14950.1 14994.3 666.0 685.9 686.8 694.5 697.6 826.2 827.9 841.1 865.6 868.5 890.2 905.0 905.4 905.5 906.0 708.4 897.1 897.5 899.9 900.6 633.6 634.2 654.5 676.0 679.4 15343.6 15422.0 15581.7 15586.5 15644.1 14223.0 14378.4 14478.1 14574.3 14607.1 13384.2 13535.3 13658.9 13852.3 13916.4 4663.7 4733.9 4817.5 4819.4 4923.0 116893.8 117203.0 117328.2 117486.0 117836.0 11256.9 11392.3 11437.7 11441.3 11491.1 23055.0 23090.8 23104.8 23155.8 23309.3 7942.2 7963.5 8029.6 8196.5 8232.3 4403.2 4458.2 4460.0 4507.0 4563.0 3525.5 3673.2 3673.4 3682.2 3683.7 646.4 659.5 661.5 670.0 675.1 567.6 599.4 622.6 632.0 633.7 8448.2 8782.2 8919.6 9034.9 9035.2 511.7 581.7 586.4 618.0 632.8 3690.4 4280.0 4324.8 4390.1 4398.1 131437.0 134115.0 147800.0 150650.2 159092.2 97303.1 120878.4 121318.0 123049.4 129850.0 74321.6 75195.0 76103.9 108405.0 110592.0 9768.4 9774.4 9790.7 9892.2 9912.4 63830.0 64954.7 65543.8 66258.3 68112.0 62793.0 70626.0 83908.6 92428.5 96735.5 10586.3 13151.2 13415.8 13598.6 13635.5 13418.5 14209.6 14243.1 14309.4 14336.4 12838.6 13338.4 13359.2 13372.6 13781.7 13093.0 13337.4 13632.7 13986.7 14103.6 11482.4 13025.2 13454.9 13575.5 13687.9 11378.9 11513.1 11646.2 11657.9 11843.5 11436.1 11490.9 11536.8 11602.2 11765.0 11:01:16 INFO - PROCESS | 1651 | -------- Summary: end -------- 11:01:16 INFO - PROCESS | 1651 | 11:01:16 INFO - TEST-INFO | 1651: exit 0 11:01:16 INFO - TEST-OK | dromaeo_css | took 1116902ms 11:01:16 INFO - TEST-START | kraken 11:01:16 INFO - operating with platform_type : mac_ 11:01:16 INFO - Initialising browser for kraken test... 11:01:16 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpFBygcn/profile http://localhost:49267/getInfo.html) 11:01:18 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 11:01:18 INFO - PROCESS | 1658 | colorDepth:24 11:01:18 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 11:01:18 INFO - PROCESS | 1658 | __metrics 11:01:19 INFO - PROCESS | 1658 | 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" 11:01:19 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:01:19 INFO - TEST-INFO | 1658: exit 0 11:01:19 INFO - Browser initialized. 11:01:19 INFO - Running cycle 1/1 for kraken test... 11:01:19 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpFBygcn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:01:31 INFO - PROCESS | 1659 | RSS: Main: 243785728 11:01:31 INFO - PROCESS | 1659 | 11:01:34 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 11:01:35 INFO - PROCESS | 1659 | RSS: Main: 294457344 11:01:35 INFO - PROCESS | 1659 | 11:01:38 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 11:01:38 INFO - PROCESS | 1659 | RSS: Main: 304943104 11:01:38 INFO - PROCESS | 1659 | 11:01:42 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 11:01:43 INFO - PROCESS | 1659 | RSS: Main: 389996544 11:01:43 INFO - PROCESS | 1659 | 11:01:45 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 11:01:45 INFO - PROCESS | 1659 | RSS: Main: 290369536 11:01:45 INFO - PROCESS | 1659 | 11:01:47 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:01:48 INFO - PROCESS | 1659 | RSS: Main: 294277120 11:01:48 INFO - PROCESS | 1659 | 11:01:51 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 11:01:51 INFO - PROCESS | 1659 | RSS: Main: 331681792 11:01:51 INFO - PROCESS | 1659 | 11:01:54 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 11:01:55 INFO - PROCESS | 1659 | RSS: Main: 333344768 11:01:55 INFO - PROCESS | 1659 | 11:01:58 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 11:01:58 INFO - PROCESS | 1659 | RSS: Main: 331247616 11:01:58 INFO - PROCESS | 1659 | 11:02:00 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:02:01 INFO - PROCESS | 1659 | RSS: Main: 311980032 11:02:01 INFO - PROCESS | 1659 | 11:02:02 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:02:03 INFO - PROCESS | 1659 | RSS: Main: 318775296 11:02:03 INFO - PROCESS | 1659 | 11:02:05 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:02:06 INFO - PROCESS | 1659 | RSS: Main: 318357504 11:02:06 INFO - PROCESS | 1659 | 11:02:09 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:02:10 INFO - PROCESS | 1659 | RSS: Main: 326987776 11:02:10 INFO - PROCESS | 1659 | 11:02:13 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:02:13 INFO - PROCESS | 1659 | RSS: Main: 328740864 11:02:13 INFO - PROCESS | 1659 | 11:02:15 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 11:02:15 INFO - PROCESS | 1659 | RSS: Main: 371884032 11:02:15 INFO - PROCESS | 1659 | 11:02:15 INFO - PROCESS | 1659 | __start_tp_report 11:02:15 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 11:02:15 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 11:02:15 INFO - PROCESS | 1659 | |i|pagename|runs| 11:02:15 INFO - PROCESS | 1659 | |0;ai-astar;99;111;113;111;112;111;116;159;164;113 11:02:15 INFO - PROCESS | 1659 | |1;audio-beat-detection;141;108;105;156;112;131;164;138;142;106 11:02:15 INFO - PROCESS | 1659 | |2;audio-dft;185;185;185;186;182;185;183;184;183;183 11:02:15 INFO - PROCESS | 1659 | |3;audio-fft;80;73;58;110;61;78;88;90;83;55 11:02:15 INFO - PROCESS | 1659 | |4;audio-oscillator;84;89;93;70;71;67;95;69;89;131 11:02:15 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;89;86;89;86;90;86;90;87;89;85 11:02:15 INFO - PROCESS | 1659 | |6;imaging-darkroom;94;94;94;95;101;127;94;95;94;95 11:02:15 INFO - PROCESS | 1659 | |7;imaging-desaturate;84;86;87;87;85;84;86;85;83;87 11:02:15 INFO - PROCESS | 1659 | |8;json-parse-financial;56;57;54;55;59;55;54;54;54;55 11:02:15 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;68;70;72;77;70;71;70;72;72;72 11:02:15 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;87;84;85;83;81;81;85;85;84;89 11:02:15 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;137;136;138;136;127;138;119;159;232;154 11:02:15 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;217;171;170;220;194;166;162;161;164;162 11:02:15 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;66;66;68;65;66;66;65;67;65;65 11:02:15 INFO - PROCESS | 1659 | __end_tp_report 11:02:15 INFO - PROCESS | 1659 | __start_cc_report 11:02:15 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,2048 11:02:15 INFO - PROCESS | 1659 | __end_cc_report 11:02:15 INFO - PROCESS | 1659 | __startTimestamp1468432935672__endTimestamp 11:02:15 INFO - PROCESS | 1659 | 11:02:15 INFO - PROCESS | 1659 | ------- Summary: start ------- 11:02:15 INFO - PROCESS | 1659 | Number of tests: 14 11:02:15 INFO - PROCESS | 1659 | 11:02:15 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:120.90 Median:114.50 stddev:21.89 (19.1%) stddev-sans-first:21.73 11:02:15 INFO - PROCESS | 1659 | Values: 99.0 111.0 113.0 111.0 112.0 111.0 116.0 159.0 164.0 113.0 11:02:15 INFO - PROCESS | 1659 | 11:02:15 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:130.30 Median:139.50 stddev:21.53 (15.4%) stddev-sans-first:22.48 11:02:15 INFO - PROCESS | 1659 | Values: 141.0 108.0 105.0 156.0 112.0 131.0 164.0 138.0 142.0 106.0 11:02:15 INFO - PROCESS | 1659 | 11:02:15 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:184.10 Median:185.00 stddev:1.29 (0.7%) stddev-sans-first:1.32 11:02:15 INFO - PROCESS | 1659 | Values: 185.0 185.0 185.0 186.0 182.0 185.0 183.0 184.0 183.0 183.0 11:02:15 INFO - PROCESS | 1659 | 11:02:15 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:77.60 Median:79.00 stddev:16.79 (21.3%) stddev-sans-first:17.79 11:02:15 INFO - PROCESS | 1659 | Values: 80.0 73.0 58.0 110.0 61.0 78.0 88.0 90.0 83.0 55.0 11:02:15 INFO - PROCESS | 1659 | 11:02:15 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:85.80 Median:86.50 stddev:19.17 (22.2%) stddev-sans-first:20.32 11:02:15 INFO - PROCESS | 1659 | Values: 84.0 89.0 93.0 70.0 71.0 67.0 95.0 69.0 89.0 131.0 11:02:15 INFO - PROCESS | 1659 | 11:02:15 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:89.00 stddev:1.89 (2.1%) stddev-sans-first:1.94 11:02:15 INFO - PROCESS | 1659 | Values: 89.0 86.0 89.0 86.0 90.0 86.0 90.0 87.0 89.0 85.0 11:02:15 INFO - PROCESS | 1659 | 11:02:15 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:98.30 Median:94.00 stddev:10.31 (11.0%) stddev-sans-first:10.81 11:02:15 INFO - PROCESS | 1659 | Values: 94.0 94.0 94.0 95.0 101.0 127.0 94.0 95.0 94.0 95.0 11:02:15 INFO - PROCESS | 1659 | 11:02:15 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:86.00 stddev:1.43 (1.7%) stddev-sans-first:1.42 11:02:15 INFO - PROCESS | 1659 | Values: 84.0 86.0 87.0 87.0 85.0 84.0 86.0 85.0 83.0 87.0 11:02:15 INFO - PROCESS | 1659 | 11:02:15 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.00 stddev:1.64 (3.0%) stddev-sans-first:1.72 11:02:15 INFO - PROCESS | 1659 | Values: 56.0 57.0 54.0 55.0 59.0 55.0 54.0 54.0 54.0 55.0 11:02:15 INFO - PROCESS | 1659 | 11:02:15 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:72.00 stddev:2.37 (3.3%) stddev-sans-first:2.17 11:02:15 INFO - PROCESS | 1659 | Values: 68.0 70.0 72.0 77.0 70.0 71.0 70.0 72.0 72.0 72.0 11:02:15 INFO - PROCESS | 1659 | 11:02:15 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:85.00 stddev:2.46 (2.9%) stddev-sans-first:2.42 11:02:15 INFO - PROCESS | 1659 | Values: 87.0 84.0 85.0 83.0 81.0 81.0 85.0 85.0 84.0 89.0 11:02:15 INFO - PROCESS | 1659 | 11:02:15 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:138.00 stddev:31.80 (23.0%) stddev-sans-first:33.50 11:02:15 INFO - PROCESS | 1659 | Values: 137.0 136.0 138.0 136.0 127.0 138.0 119.0 159.0 232.0 154.0 11:02:15 INFO - PROCESS | 1659 | 11:02:15 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.70 Median:170.50 stddev:23.07 (13.5%) stddev-sans-first:19.88 11:02:15 INFO - PROCESS | 1659 | Values: 217.0 171.0 170.0 220.0 194.0 166.0 162.0 161.0 164.0 162.0 11:02:15 INFO - PROCESS | 1659 | 11:02:15 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.90 Median:66.00 stddev:0.99 (1.5%) stddev-sans-first:1.05 11:02:15 INFO - PROCESS | 1659 | Values: 66.0 66.0 68.0 65.0 66.0 66.0 65.0 67.0 65.0 65.0 11:02:15 INFO - PROCESS | 1659 | -------- Summary: end -------- 11:02:15 INFO - PROCESS | 1659 | 11:02:16 INFO - TEST-INFO | 1659: exit 0 11:02:16 INFO - TEST-OK | kraken | took 59835ms 11:02:16 INFO - SUITE-END | took 1176s 11:02:16 INFO - Completed test suite (00:19:37) 11:02:16 INFO - javascript benchmark 11:02:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3345.5818022747158, 3350.6649335066495, 3369.679880411361, 3377.915552111195, 3427.828608569571, 5619.5785316101355, 5650.660960342378, 5665.405132461094, 5764.769409223636, 5790.565707571928, 5663.122216056508, 5713.400092990228, 5777.797777077803, 5803.390643982374, 5835.095560188167, 4342.782860856961, 5096.949030509703, 5119.206522988924, 5135.178371460567, 5284.762185701634, 5288.576913846883, 5291.444398338177, 5309.575233981292, 5343.278657381262, 5386.757595908193, 5139.58883289336, 5182.922256166161, 5195.584353251731, 5216.556592689626, 5231.686098834063, 5221.1124108901195, 5247.606429517763, 5249.396319423277, 5252.973735131338, 5253.264542963988, 5052.216906379517, 5116.692998420107, 5149.0, 5208.8958220835375, 5239.187925871496, 5624.634398764068, 5637.605367624268, 5713.857153571153, 5748.683822389767, 5794.478496935296, 6192.442680158807, 6242.500599951993, 6251.9687401562705, 6255.655938923357, 6257.3116957135135, 6119.746288422662, 6184.381561843779, 6210.410011048988, 6276.592021518587, 6298.811035668938, 5842.444967728059, 5858.443447872445, 5860.472557469763, 5862.443067908542, 5872.324682661463, 6240.719167637467, 6315.621062736251, 6317.778877739349, 6321.336259692666, 6321.683915804191, 6088.238970128734, 6106.582013105394, 6142.04798256271, 6166.81499555014, 6183.288921773964, 6279.560430769802, 6298.307186209513, 6298.464630506458, 6306.590071645328, 6311.526635502356, 6030.33666296707, 6087.482563982023, 6213.720382582795, 6247.0629405589525, 6254.906176407358, 6153.538484613582, 6192.59748116371, 6202.472789812917, 6210.254769427607, 6271.341509141565, 4941.234108713155, 4956.033573453142, 4958.107540642719, 4964.205727083649, 5002.699838009731, 4422.668299877522, 4439.334099884978, 4477.6417886569, 4478.283474644041, 4502.099580083996, 5647.802326918621, 5666.263385759825, 5681.573881958787, 5711.914321285184, 5802.564807639444, 6289.652427039552, 6299.370062993664, 6306.936930630635, 6307.0, 6310.148130002391, 5529.529989950819, 5715.2855893013375, 5724.313082430135, 5749.453801888814, 5775.6823374714795, 5346.1713434417725, 5346.278252435949, 5387.0676451659765, 5422.566194704415, 5443.673379597237, 6282.434580887742, 6302.1492098566105, 6305.873882522324, 6313.3686631336495, 6319.083732858618, 6116.609690437491, 6256.061590761422, 6313.147725057059, 6334.1765570475545, 6351.237851457855, 6223.471004964629, 6272.8431789203805, 6296.5592408530965, 6313.936860631335, 6318.494520438446, 6186.288576813724, 6194.38056194377, 6210.4100110489435, 6214.658193799203, 6234.937650623436, 6100.096771792299, 6191.504679625529, 6226.346233645402, 6255.906161407492, 6333.240011198685, 5195.480451954774, 6103.097294528758, 6130.5402094842175, 6135.539834512519, 6153.8307721532765, 6102.115193297036, 6132.41742034506, 6134.601250918676, 6139.079138129317, 6151.876962460637, 5304.257403963526, 5799.739011744397, 5844.386339434298, 5955.166276721176, 5987.820365389045, 5514.310711161105, 6051.697415129138, 6058.394160583906, 6098.810731907235, 6131.110988906673, 5939.673317967553, 5965.135055417027, 5977.521798256216, 5978.790742323998, 6006.309274433496], "value": 5700.623811320786, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.261607588617, 517.0899217377416, 537.6209772110662, 540.3893600231737, 549.2447884159282, 29863.253418664517, 41203.0, 41238.0, 41321.58678413215, 41339.37990930131, 43739.78130109345, 43992.900177495576, 44028.0, 44087.0, 44160.558394416046, 8673.783155421133, 9613.279004074704, 9796.363236389647, 9797.657082002132, 9834.55744491496, 8552.78618034548, 11716.589919352824, 11871.703207419798, 11905.345206013666, 12084.516619335216, 11600.187986840925, 11809.586664466746, 11840.703982400466, 11879.406029698524, 11916.821247681291, 8630.741077767678, 8811.339149563744, 8840.469571825646, 11844.7631047379, 11941.58204462844, 8751.78120546985, 11405.771884562346, 11642.476088576035, 11673.241239319417, 11943.880561194364, 41799.95500112491, 41937.79031104825, 42076.0, 42157.78921105375, 42194.57805421968, 3528.904612548864, 3556.466530020492, 3604.7296452766145, 3641.18073433478, 4272.6795490338345, 31386.843065784753, 32756.508652370234, 32793.0, 32987.6701232987, 33085.503717444255, 3316.4859446785786, 3327.101682545699, 3378.6114596821435, 3400.8129552874584, 3441.2601290722614, 534061.9999999962, 534076.0, 534233.0, 534378.0, 534912.0, 33805.32389352199, 34034.65965340315, 61991.690041549504, 62371.376286237464, 62458.37541624525, 36054.639453605654, 36188.914332570064, 36215.0, 36341.454878176846, 36347.09132271714, 15036.548903532912, 15122.54632361031, 15197.088174709554, 15208.771868421982, 15231.238438078051, 11474.885251147549, 11621.360825154528, 12552.807483289072, 12708.428120734588, 12751.107422480338, 6682.866342673119, 7905.920940790634, 7976.52140871538, 7976.84046319082, 7979.401544884041, 3235.9838200808845, 3274.9017529474154, 3284.5730055093163, 3308.669133086672, 3309.2057906102364, 1005.9949700251452, 1011.4891979550428, 1027.075631931253, 1069.5454431866426, 1070.5557193764657, 31096.378072438878, 31119.066428007198, 31229.843850781053, 31982.880599178916, 32067.0, 1014.2697257974247, 1102.6030628973563, 1140.589387820384, 1196.4017991004498, 1252.3988485526816, 16114.677706445806, 16285.511434656979, 16366.345346186019, 16379.0, 16847.0, 717872.0, 765699.17150415, 766294.0, 767929.0, 1064268.0, 61689.0, 61705.0, 62424.63027588116, 62432.0, 62504.06243906345, 1043.7025447747508, 1248.4694005047636, 1410.4922227997913, 1432.1335591966917, 1452.3609611770999, 28102.437951240452, 29651.703482964895, 32871.67128328686, 33346.499802503466, 33351.0, 48071.5192848067, 58462.830743384045, 58552.707236463546, 58638.82722345444, 58873.11690324734, 662888.0, 670399.0, 675110.0, 679962.0, 684056.0, 24181.51636967286, 24290.87854560716, 26646.733532664424, 27910.44179116447, 27993.300167495974, 38382.0, 39919.0, 41997.580024199364, 46951.765241173576, 51898.0, 39455.60544394519, 40426.0, 41440.0, 41500.962475937136, 45390.865228369556, 41824.581754182065, 43309.35035974526, 44351.66944991655, 44953.0, 45701.54298456973, 41169.1766164669, 42071.7896410516, 42114.57885421229, 42650.1469970605, 43080.78459607682, 44084.779576101915, 44161.558384415745, 44267.11465770731, 44986.0, 45019.77490112528, 22596.0, 27885.141385106475, 28049.43525294279, 28159.763638451197, 28201.41152008327], "value": 19856.84916111144, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.4744035822505, 378.17557724161327, 389.04295433234245, 390.2409812913882, 407.0495393256745, 20107.29624463144, 20548.280810171647, 20831.687524687124, 21541.24605638803, 21591.0, 20362.490937726565, 20810.375688729324, 21117.366479005617, 21471.248506302283, 21517.17104046158, 5527.917081243784, 5594.272744543214, 5755.9927012772805, 6019.879602407949, 6052.485538729214, 5964.940350596504, 6003.0, 6055.9394406056035, 6057.30341010785, 6062.4543791058795, 5273.288106105665, 5907.911381329283, 5912.674802885861, 5935.465808077272, 5951.702414879239, 5680.488756011979, 5866.237389139427, 5887.175795388649, 5965.075413310966, 5972.0444728843395, 5086.186210206386, 5112.130937740593, 5118.027574760784, 5129.333186685744, 5134.973005398935, 25358.619620705704, 25440.745592544023, 25502.0, 25720.485590288277, 25761.871190643928, 2213.3581261434165, 2228.3092241405216, 2417.4319035026756, 2613.777828884547, 2984.867002513897, 15701.214939253106, 15723.292451839694, 15830.734299758002, 16465.25906334218, 16504.83495165057, 1813.292815801825, 1906.323255244396, 1975.7233987241798, 2002.5594369238745, 2007.8393728501685, 76404.61797691032, 101168.49415752874, 102825.0, 107977.0, 111697.44151279192, 10714.46427678613, 10833.104206763785, 10873.891261087289, 10917.727056823484, 11049.28179668319, 12192.329421881881, 12646.304453254976, 13308.070329802125, 13350.732985340434, 13353.73292534144, 7942.166072562298, 7956.164602716747, 8146.714864979697, 8425.95787021061, 8616.009158946676, 8032.357411407073, 8675.0, 8682.86975695365, 8766.167214114648, 8800.603972821238, 5744.339400968858, 5985.191999080069, 6284.9001424751405, 6584.539082264196, 6605.900911486331, 1545.4204673247532, 1820.0, 1847.5658220318214, 1848.3807924345226, 1848.6949653307322, 874.61079819481, 915.8763566918514, 967.2117224462041, 1005.6580762540625, 1030.8402197659375, 22333.330000100024, 22376.875265220668, 22653.886730566242, 22893.771062289165, 22898.0, 830.3979614779237, 935.3817895041481, 945.6879229854131, 953.4517652349788, 964.5514835601339, 9450.480223587769, 10502.002309780559, 11726.70683232926, 11882.28706277626, 11895.524179032915, 580804.0, 584668.0, 595871.0, 598631.0, 599073.0, 100579.49710251403, 120945.0, 122110.38944805218, 125157.0, 127068.36465817796, 1009.2682804966487, 1017.0592202212923, 1031.5409642708923, 1041.2138835179392, 1053.4100903494068, 17135.48593542196, 19853.106610202285, 19868.304609339182, 19882.204711811366, 19931.701024484355, 51379.74310128426, 52288.47711522836, 53559.0, 54366.91266174575, 55575.0, 51379.0, 71450.2137446568, 71507.28492715214, 71868.64065679639, 72928.63535682288, 7845.137034926268, 7862.410319225966, 8012.919870801451, 8041.356691464785, 8259.174082591693, 11473.967342938842, 11854.762904741685, 12626.684332891751, 12771.297578632893, 12786.296753678636, 11223.15826313046, 11391.772164556496, 11432.885671143182, 11471.483783229942, 11491.310521368745, 8619.569021549023, 10934.507947142556, 11142.554297827995, 11242.606508772154, 11262.831057534308, 11378.0, 11390.943045284721, 11441.599544015919, 11492.770144597229, 11665.24175928562, 7864.488808227676, 7911.406644501571, 7921.841563168819, 8091.635876385694, 8207.466514676766, 11112.277701949348, 11147.386893720599, 11286.49210785533, 11287.492062857027, 11414.828777568177], "value": 9683.106730545016, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1365.9521916732904, 1500.1224283793981, 1527.7555591105424, 1576.984230157699, 1723.138430784607, 7737.41969352298, 8015.839683206333, 8017.278444939954, 8022.478538894975, 8026.1973802619705, 8342.416030877841, 8597.656093756243, 8629.741107766762, 8696.782580435492, 8703.390762646617, 2136.583366243583, 2351.9412014699683, 2353.0, 2354.0, 2361.5749165150264, 1907.4563749331444, 2332.47519308156, 2348.471593891369, 2350.9529809403803, 2351.6119840226343, 2299.701038864945, 2322.291701031185, 2330.8601483910934, 2336.9532609347802, 2339.1812865497027, 2307.492351682627, 2330.4406942333803, 2332.242021343062, 2332.953340933172, 2340.625499920022, 2321.141177764221, 2329.312852708455, 2332.486852892378, 2333.778291062363, 2333.8482998605205, 8687.218150366498, 8801.559922003938, 8820.2061814437, 8821.647134114628, 8833.0, 5122.154844450662, 5125.692458452505, 5286.814961476368, 5286.969041036999, 5295.152775555931, 3508.736844736655, 3529.858805647771, 3597.478365636968, 3657.4330978698345, 3680.0799800049986, 1017.9847302290472, 1019.3934608907688, 1037.247995203479, 1050.369778133114, 1102.8731695855, 11081.168912331608, 11092.55629774816, 11102.444877756161, 11369.829452558219, 11380.54477820878, 4546.636269098464, 4636.768161591907, 4760.833370832004, 4763.237881938873, 4766.261229509431, 9902.900970990198, 9917.355371900947, 9918.80162396748, 9933.503324833873, 9941.105300522842, 5337.172738225504, 5345.278387417727, 5346.251524786533, 5348.812791552277, 5352.2506849041165, 4381.6494680425485, 4537.863864084083, 4743.644226683013, 4874.098291816025, 4900.926486102711, 3882.8835134946, 3896.376579747227, 3983.203359328146, 4025.597440255951, 4046.4132700758223, 1072.1826504075673, 1129.8983091521638, 1197.8442802435627, 1285.8135570342247, 1289.548657969703, 624.3288464900251, 774.4540099230029, 818.382121498265, 860.9827803443897, 982.5087456271864, 5519.779208831602, 5799.333076696234, 5818.127280907897, 5843.795467158629, 6011.819645410645, 907.2696479334068, 921.5899673499556, 940.4780346907532, 943.2501161576563, 961.4904100826573, 3784.413415920537, 3887.067103895045, 3913.726039177287, 3959.0, 3994.2211268802303, 10242.283040187265, 10508.842367364496, 10665.306755060896, 10681.946590266998, 10699.679009629723, 10535.525901334458, 10599.311044782064, 10601.787964240671, 10639.361638301727, 10663.4135122569, 7896.407769417317, 8118.5128892265475, 8200.31893461827, 8221.588920554068, 8232.711855085043, 5654.915176272441, 5753.280839895013, 5775.5090817281, 5780.624259423124, 5780.884382312414, 9686.370385924893, 10109.545070471699, 10116.494175291353, 10117.696469105938, 10119.79760404773, 8636.78408039804, 9780.657676981271, 10152.796944061225, 10176.236782241214, 10273.691789246335, 7322.121345438582, 7323.890141647986, 7403.925960740324, 7447.218042105501, 7494.475386722877, 8086.393520485869, 8134.552599607078, 8178.83642327162, 8194.713185038496, 8196.67213311461, 5873.4126587340925, 6881.621510816954, 7278.417726581755, 7279.8544029120185, 7565.4325925556905, 7077.5753454792875, 7253.419726421979, 7278.781636550912, 7339.266073392618, 7392.260773922565, 6925.861482770417, 6983.720651173896, 7036.226015138228, 7048.154221493453, 7051.541649792747, 4576.519465456212, 4786.497417771223, 4789.473157952692, 4861.951380486292, 4919.262110683426, 4693.812247510062, 4719.7404142772475, 4858.17411040117, 4868.367112275381, 4998.750062496788], "value": 4499.743497806635, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.7244326554671, 306.4851050235605, 310.5915720827113, 311.9844007799611, 313.9874405023799, 4622.283546050359, 5278.498542638448, 5309.8938021239555, 5342.679439233644, 5376.381716102649, 5258.815941442051, 5265.236540701596, 5290.206469029647, 5306.8407947761625, 5315.441878602749, 3629.782213067211, 3804.790736509498, 3870.6903447724117, 3941.605839416064, 4005.0387906902424, 3383.4924761285756, 3502.4396096624546, 3689.981550092246, 3940.921181576367, 4082.2243773682903, 3281.3273278977754, 4007.639312461878, 4032.6370626643597, 4050.9392359114636, 4061.3501839705655, 3700.0379901225606, 3728.6644202021675, 3819.2361527694434, 3968.305546529346, 4011.859584914514, 3482.6517348265224, 3484.6341134181052, 3516.982415087934, 3526.312369088029, 3539.94690079649, 5099.863583895914, 5104.82589004531, 5130.410002849682, 5137.073597400975, 5167.3282473278605, 3652.4886515887797, 4533.229351010336, 4598.609118224955, 4599.678022538424, 4632.606228470584, 4770.0698363819065, 4867.831720387082, 5324.440933701944, 5468.316460442445, 5558.749856256478, 3898.688104951597, 3927.1163988102735, 3929.8624548140965, 3937.0551067743822, 3939.0, 4637.605803506706, 4870.001649661811, 5469.890602187934, 5797.043487824504, 5843.831233753224, 3310.817905015247, 3534.575850897909, 3978.8806335809973, 4020.0, 4024.1549274652066, 3309.4539400998565, 3983.183447393277, 4064.9390259146135, 4071.4096456014304, 4087.9780054986254, 3968.2261958917734, 4578.244589642738, 4615.169269531517, 4663.813447462131, 4668.0, 4849.830255941096, 4874.975625121851, 5206.557442617344, 5544.611877168641, 5606.635568688022, 1215.4166000319897, 1332.1407692038774, 1337.7057047449546, 1383.3774801339437, 1396.4902810474105, 754.7660225330166, 831.5177197225595, 857.5926434943477, 869.4392117084445, 877.8156587116777, 5040.54635082837, 5044.646874718808, 5072.568831649277, 5089.465606111379, 5089.567386772092, 4821.662483626183, 5467.824417750203, 5625.353084395347, 5648.943510564924, 5689.146628005832, 4400.75795831226, 4570.794314255866, 5047.9495205048215, 5122.718250496258, 5150.793968241303, 2600.7386417587454, 2766.243435418524, 2986.0590616099707, 2992.7007299270335, 2995.5356919677483, 5124.898146898435, 5227.3465816772905, 5679.046895614258, 5894.528437724972, 5917.615355001911, 5619.831405057855, 5649.717514124277, 5749.453801888898, 5795.507381872588, 5833.433289352672, 5731.570132240098, 5754.223179870663, 5757.9424205757405, 5795.101759227411, 5838.970805145947, 5406.999705054577, 5698.344690360662, 5705.572082093777, 5717.799877004285, 5750.424957504216, 5192.766325515437, 5245.747636465416, 5300.091966891993, 5524.613277070566, 5543.61194716366, 5753.597248192586, 5779.566532509994, 5844.912326315024, 5867.647941123546, 5886.440788125121, 3641.126129728899, 3685.6583479963533, 3865.8260378282484, 3881.4177873319227, 3888.5333759948985, 4958.355413796184, 5547.556195504269, 5637.661740295595, 5647.124695672177, 5657.660540367591, 5468.5351745100525, 5502.367227768857, 5519.613627046068, 5522.751476183662, 5527.844140785017, 4682.719036857799, 4683.742394168354, 4832.468428472936, 5189.612876794742, 5556.972215138899, 5341.145416733304, 5388.515033646991, 5390.326209223847, 5409.810656626999, 5413.918791218056, 4538.65960053004, 5181.3782346118705, 5408.75660595282, 5445.603934268909, 5512.972435137799, 4819.554133759788, 5059.494050594911, 5435.130379139318, 5440.727963601883, 5501.559875209893], "value": 4033.5344715643287, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [753.8906858505517, 778.179298054552, 800.8998875140608, 813.324940299551, 863.5768473448011, 14890.553283401501, 16423.34306627735, 16456.25946832394, 16481.175941202953, 16508.669826603462, 14664.926675366609, 14918.627034324147, 14944.0, 14950.10299382038, 14994.250287485636, 665.9766908158227, 685.8971154326841, 686.7905288886888, 694.4687314204638, 697.6476879176034, 826.1944604011101, 827.9130691277419, 841.1252297610508, 865.6018231613434, 868.452874688945, 890.1765866573426, 904.9638164301871, 905.4069584422214, 905.4522014181424, 906.0169715858302, 708.3872450330479, 897.0715309654541, 897.5018864530183, 899.9190072893439, 900.5632268349846, 633.6198281031344, 634.2452481546944, 654.4764188649109, 675.9695813688396, 679.37497502298, 15343.619074283257, 15421.997570157904, 15581.688366232613, 15586.532404027897, 15644.061356318658, 14223.0, 14378.352974116086, 14478.131312121306, 14574.344154513072, 14607.123572585679, 13384.19694818314, 13535.255560944144, 13658.931705341509, 13852.307384630729, 13916.443342266297, 4663.720176789369, 4733.857984260478, 4817.51824817519, 4819.373481447389, 4923.040007198598, 116893.83106168914, 117203.0, 117328.24007639807, 117486.0, 117836.0, 11256.887431125748, 11392.25950313227, 11437.656870293904, 11441.313521188755, 11491.138164637685, 23055.0, 23090.769092309198, 23104.76895231026, 23155.768442315697, 23309.300720978397, 7942.205779422012, 7963.482373645695, 8029.638666260033, 8196.467229630054, 8232.300254478316, 4403.229434848889, 4458.242098843204, 4459.955400445954, 4506.974723183692, 4563.041761230113, 3525.4888041233876, 3673.210259794157, 3673.393890008172, 3682.226732386229, 3683.723720720957, 646.3859333633068, 659.5119611487464, 661.5203977116552, 670.0418401685539, 675.0650349266301, 567.5743192605546, 599.4485073732177, 622.6139793328166, 632.0456111272013, 633.740166531729, 8448.23965843077, 8782.180389654395, 8919.559068967925, 9034.86447703285, 9035.232005279624, 511.7211119939642, 581.7411251992822, 586.3608666553478, 618.0103034677809, 632.7880160146402, 3690.4371013800624, 4279.97860010698, 4324.777621372253, 4390.0708865565775, 4398.120375924764, 131437.0, 134115.0, 147800.0, 150650.24674876555, 159092.20453897657, 97303.13484325897, 120878.3956080214, 121318.0, 123049.38475307566, 129850.0, 74321.6357150296, 75195.0, 76103.85844212452, 108405.0, 110592.0, 9768.413895166314, 9774.413535187912, 9790.657326993522, 9892.208623310262, 9912.35569687968, 63830.0, 64954.67522662356, 65543.77334153248, 66258.33741662714, 68112.0, 62793.0, 70626.0, 83908.58045709733, 92428.53785731297, 96735.51632241794, 10586.311889727143, 13151.21092734439, 13415.798763018367, 13598.592042238748, 13635.522756703467, 13418.463261469431, 14209.644758881112, 14243.145411275356, 14309.35607897668, 14336.354864031353, 12838.614841554769, 13338.399772010476, 13359.198448093146, 13372.598822035354, 13781.655458613615, 13093.0, 13337.368345330864, 13632.659183520094, 13986.650333741738, 14103.647408814863, 11482.425878706199, 13025.153365031243, 13454.865451345753, 13575.52485662997, 13687.905372006195, 11378.943105284421, 11513.13651476126, 11646.184767066225, 11657.941710291394, 11843.467043983213, 11436.142289328167, 11490.88509114898, 11536.82694759596, 11602.245854019462, 11765.0], "value": 7817.660952806632, "unit": "score"}], "name": "dromaeo_css", "value": 7333.43619305587, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 111.0, 113.0, 111.0, 112.0, 111.0, 116.0, 159.0, 164.0, 113.0], "value": 120.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 108.0, 105.0, 156.0, 112.0, 131.0, 164.0, 138.0, 142.0, 106.0], "value": 130.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 185.0, 186.0, 182.0, 185.0, 183.0, 184.0, 183.0, 183.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 73.0, 58.0, 110.0, 61.0, 78.0, 88.0, 90.0, 83.0, 55.0], "value": 77.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 89.0, 93.0, 70.0, 71.0, 67.0, 95.0, 69.0, 89.0, 131.0], "value": 85.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 86.0, 90.0, 86.0, 90.0, 87.0, 89.0, 85.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 101.0, 127.0, 94.0, 95.0, 94.0, 95.0], "value": 98.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 87.0, 87.0, 85.0, 84.0, 86.0, 85.0, 83.0, 87.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 54.0, 55.0, 59.0, 55.0, 54.0, 54.0, 54.0, 55.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 72.0, 77.0, 70.0, 71.0, 70.0, 72.0, 72.0, 72.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 85.0, 83.0, 81.0, 81.0, 85.0, 85.0, 84.0, 89.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 138.0, 136.0, 127.0, 138.0, 119.0, 159.0, 232.0, 154.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [217.0, 171.0, 170.0, 220.0, 194.0, 166.0, 162.0, 161.0, 164.0, 162.0], "value": 178.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 68.0, 65.0, 66.0, 66.0, 65.0, 67.0, 65.0, 65.0], "value": 65.9, "unit": "score"}], "name": "kraken", "value": 1473.3999999999999, "alertThreshold": 2.0}]} 11:02:16 INFO - Return code: 0 11:02:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:02:16 INFO - # TBPL SUCCESS # 11:02:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:02:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:02:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:02:16 INFO - 2016-07-13 11:02:16.979 screenresolution[1663:10672] starting screenresolution argv=screenresolution get 11:02:16 INFO - 2016-07-13 11:02:16.982 screenresolution[1663:10672] Display 0: 1600x1200x32@60 11:02:16 INFO - 2016-07-13 11:02:16.988 screenresolution[1664:10674] starting screenresolution argv=screenresolution list 11:02:16 INFO - Available Modes on Display 0 11:02:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:02:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:02:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:02:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:02:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:02:17 INFO - Intel Iris: 11:02:17 INFO - Chipset Model: Intel Iris 11:02:17 INFO - Type: GPU 11:02:17 INFO - Bus: Built-In 11:02:17 INFO - VRAM (Dynamic, Max): 1536 MB 11:02:17 INFO - Vendor: Intel (0x8086) 11:02:17 INFO - Device ID: 0x0a2e 11:02:17 INFO - Revision ID: 0x0009 11:02:17 INFO - Displays: 11:02:17 INFO - Display: 11:02:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:02:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:02:17 INFO - Display Serial Number: 1600x1200 60 11:02:17 INFO - Main Display: Yes 11:02:17 INFO - Mirror: Off 11:02:17 INFO - Online: Yes 11:02:17 INFO - Rotation: Supported 11:02:17 INFO - Return code: 0 11:02:17 INFO - Running post-action listener: _resource_record_post_action 11:02:17 INFO - ##### 11:02:17 INFO - ##### Finished run-tests step (success) 11:02:17 INFO - ##### 11:02:17 INFO - Running post-run listener: _resource_record_post_run 11:02:17 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 31899136; Write bytes: 707259392; Read time: 216; Write time: 1985 11:02:17 INFO - TinderboxPrint: CPU usage
53.7% 11:02:17 INFO - TinderboxPrint: I/O read bytes / time
31,899,136 / 216 11:02:17 INFO - TinderboxPrint: I/O write bytes / time
707,259,392 / 1,985 11:02:17 INFO - TinderboxPrint: CPU idle
2,246.8 (46.3%) 11:02:17 INFO - TinderboxPrint: CPU system
971.8 (20.0%) 11:02:17 INFO - TinderboxPrint: CPU user
1,631.0 (33.6%) 11:02:17 INFO - TinderboxPrint: Swap in / out
434,499,584 / 0 11:02:17 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 407929344; Write bytes: 409675776; Read time: 30530; Write time: 858 11:02:17 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 29310464; Write bytes: 296264704; Read time: 194; Write time: 1121 11:02:17 INFO - Running post-run listener: _upload_blobber_files 11:02:17 INFO - Blob upload gear active. 11:02:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:02:17 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 11:02:17 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'] 11:02:17 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 11:02:18 INFO - (blobuploader) - INFO - Open directory for files ... 11:02:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:02:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:02:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:02:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:02:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:02:19 INFO - (blobuploader) - INFO - Done attempting. 11:02:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:02:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:02:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:02:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:02:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:02:20 INFO - (blobuploader) - INFO - Done attempting. 11:02:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:02:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:02:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:02:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:02:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:02:22 INFO - (blobuploader) - INFO - Done attempting. 11:02:22 INFO - (blobuploader) - INFO - Iteration through files over. 11:02:22 INFO - Return code: 0 11:02:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:02:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:02:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b3b7736ab370f9c6910082c65a3538727d2d6c35a4edebd88d52f886efd999b709914b053d8b7fe8e2e20c76ac5b9e12f86e3ea59fd7779e40abeeaf91608ea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f754cbaa9a3c739408094aa7e2d555b3ad80d53e9b42b85c3391bd16c157c4de0ddb0cf0e2bc328007cf9184cbb3f88c6fd9ae40876028de97c862885ed8210d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f8d4673733c60c30cc7544dc233103c865ab6626a76a8d6cce6bf9fac4a86c8c93625555dca82861f6801b54905485d6485bab538e80747c921be3f65e3c052"} 11:02:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:02:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:02:22 INFO - Contents: 11:02:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b3b7736ab370f9c6910082c65a3538727d2d6c35a4edebd88d52f886efd999b709914b053d8b7fe8e2e20c76ac5b9e12f86e3ea59fd7779e40abeeaf91608ea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f754cbaa9a3c739408094aa7e2d555b3ad80d53e9b42b85c3391bd16c157c4de0ddb0cf0e2bc328007cf9184cbb3f88c6fd9ae40876028de97c862885ed8210d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f8d4673733c60c30cc7544dc233103c865ab6626a76a8d6cce6bf9fac4a86c8c93625555dca82861f6801b54905485d6485bab538e80747c921be3f65e3c052"} 11:02:22 INFO - Running post-run listener: copy_logs_to_upload_dir 11:02:22 INFO - Copying logs to upload dir... 11:02:22 INFO - mkdir: /builds/slave/test/build/upload/logs 11:02:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.119391 ========= master_lag: 49.15 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 57 secs) (at 2016-07-13 11:03:11.852360) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 37 secs) (at 2016-07-13 11:03:11.857478) ========= 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.JtlgCsAIRx/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.G4v9yvcsid/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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/3b3b7736ab370f9c6910082c65a3538727d2d6c35a4edebd88d52f886efd999b709914b053d8b7fe8e2e20c76ac5b9e12f86e3ea59fd7779e40abeeaf91608ea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f754cbaa9a3c739408094aa7e2d555b3ad80d53e9b42b85c3391bd16c157c4de0ddb0cf0e2bc328007cf9184cbb3f88c6fd9ae40876028de97c862885ed8210d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f8d4673733c60c30cc7544dc233103c865ab6626a76a8d6cce6bf9fac4a86c8c93625555dca82861f6801b54905485d6485bab538e80747c921be3f65e3c052"} build_url:https://queue.taskcluster.net/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011276 build_url: 'https://queue.taskcluster.net/v1/task/EA9AtkhhTuOoRbB0humbEw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3b3b7736ab370f9c6910082c65a3538727d2d6c35a4edebd88d52f886efd999b709914b053d8b7fe8e2e20c76ac5b9e12f86e3ea59fd7779e40abeeaf91608ea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f754cbaa9a3c739408094aa7e2d555b3ad80d53e9b42b85c3391bd16c157c4de0ddb0cf0e2bc328007cf9184cbb3f88c6fd9ae40876028de97c862885ed8210d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1f8d4673733c60c30cc7544dc233103c865ab6626a76a8d6cce6bf9fac4a86c8c93625555dca82861f6801b54905485d6485bab538e80747c921be3f65e3c052"}' ========= master_lag: 37.53 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 37 secs) (at 2016-07-13 11:03:49.395030) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 19 secs) (at 2016-07-13 11:03:49.395425) ========= 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.JtlgCsAIRx/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.G4v9yvcsid/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/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.005093 ========= master_lag: 19.10 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 19 secs) (at 2016-07-13 11:04:08.503452) ========= ========= Started reboot skipped (results: 3, elapsed: 41 secs) (at 2016-07-13 11:04:08.503798) ========= ========= Finished reboot skipped (results: 3, elapsed: 41 secs) (at 2016-07-13 11:04:49.603136) ========= ========= Total master_lag: 105.85 =========