builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0147 starttime: 1468257912.75 results: success (0) buildid: 20160711143735 builduid: a945a57d02a8428eb68bbcc6b19bbba5 revision: 214884d507ee369c1cf14edb26527c4f9a97bf48 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 10:25:12.754334) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-11 10:25:12.754811) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 10:25:12.755171) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-11 10:25:12.828874) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 10:25:12.829168) ========= 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.sGcDOxx4Ep/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.7UK0VQaA24/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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-11 10:25:12-- 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% 156M=0s 2016-07-11 10:25:12 (156 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.155562 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 10:25:13.013642) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 10:25:13.014336) ========= 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.sGcDOxx4Ep/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.7UK0VQaA24/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.027452 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 10:25:13.068560) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 10:25:13.069320) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 214884d507ee369c1cf14edb26527c4f9a97bf48 --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 214884d507ee369c1cf14edb26527c4f9a97bf48 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sGcDOxx4Ep/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.7UK0VQaA24/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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-11 10:25:13,151 truncating revision to first 12 chars 2016-07-11 10:25:13,151 Setting DEBUG logging. 2016-07-11 10:25:13,151 attempt 1/10 2016-07-11 10:25:13,151 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/214884d507ee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-11 10:25:14,237 unpacking tar archive at: mozilla-central-214884d507ee/testing/mozharness/ program finished with exit code 0 elapsedTime=1.366520 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-11 10:25:14.465602) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 10:25:14.466394) ========= script_repo_revision: 214884d507ee369c1cf14edb26527c4f9a97bf48 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-11 10:25:14.466836) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 10:25:14.467145) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-11 10:25:14.487843) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 38 secs) (at 2016-07-11 10:25:14.488506) ========= /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.sGcDOxx4Ep/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.7UK0VQaA24/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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:25:14 INFO - MultiFileLogger online at 20160711 10:25:14 in /builds/slave/test 10:25: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:25:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:25:14 INFO - {'append_to_log': False, 10:25:14 INFO - 'base_work_dir': '/builds/slave/test', 10:25:14 INFO - 'blob_upload_branch': 'Firefox', 10:25:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:25:14 INFO - 'branch': 'Firefox', 10:25:14 INFO - 'buildbot_json_path': 'buildprops.json', 10:25:14 INFO - 'config_files': ('talos/mac_config.py',), 10:25:14 INFO - 'default_actions': ('clobber', 10:25:14 INFO - 'read-buildbot-config', 10:25:14 INFO - 'download-and-extract', 10:25:14 INFO - 'populate-webroot', 10:25:14 INFO - 'create-virtualenv', 10:25:14 INFO - 'install', 10:25:14 INFO - 'run-tests'), 10:25:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:25:14 INFO - 'download_minidump_stackwalk': True, 10:25:14 INFO - 'download_symbols': 'ondemand', 10:25:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:25:14 INFO - 'tooltool.py': '/tools/tooltool.py', 10:25:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:25:14 INFO - '/tools/misc-python/virtualenv.py')}, 10:25:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:25:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:25:14 INFO - 'installer_path': 'installer.exe', 10:25:14 INFO - 'log_level': 'info', 10:25:14 INFO - 'log_name': 'talos', 10:25:14 INFO - 'log_to_console': True, 10:25:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:25:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:25:14 INFO - 'opt_config_files': (), 10:25:14 INFO - 'pip_index': False, 10:25:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:25:14 INFO - 'cmd': ('bash', 10:25:14 INFO - '-c', 10:25:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:25:14 INFO - 'enabled': True, 10:25:14 INFO - 'halt_on_failure': False, 10:25:14 INFO - 'name': 'check_screen_resolution'},), 10:25:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:25:14 INFO - 'cmd': ('bash', 10:25:14 INFO - '-c', 10:25:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:25:14 INFO - 'enabled': True, 10:25:14 INFO - 'halt_on_failure': False, 10:25:14 INFO - 'name': 'check_screen_resolution'},), 10:25:14 INFO - 'run_cmd_checks_enabled': True, 10:25:14 INFO - 'sps_profile': False, 10:25:14 INFO - 'sps_profile_interval': 0, 10:25:14 INFO - 'suite': 'dromaeojs', 10:25:14 INFO - 'system_bits': '32', 10:25:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:25:14 INFO - 'title': 't-yosemite-r7-0147', 10:25:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:25:14 INFO - 'use_talos_json': True, 10:25:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:25:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:25:14 INFO - 'work_dir': 'build'} 10:25:14 INFO - ##### 10:25:14 INFO - ##### Running clobber step. 10:25:14 INFO - ##### 10:25:14 INFO - Running pre-action listener: _resource_record_pre_action 10:25:14 INFO - Running main action method: clobber 10:25:14 INFO - rmtree: /builds/slave/test/build 10:25:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:25:16 INFO - Running post-action listener: _resource_record_post_action 10:25:16 INFO - ##### 10:25:16 INFO - ##### Finished clobber step (success) 10:25:16 INFO - ##### 10:25:16 INFO - ##### 10:25:16 INFO - ##### Running read-buildbot-config step. 10:25:16 INFO - ##### 10:25:16 INFO - Running pre-action listener: _resource_record_pre_action 10:25:16 INFO - Running main action method: read_buildbot_config 10:25:16 INFO - Using buildbot properties: 10:25:16 INFO - { 10:25:16 INFO - "project": "", 10:25:16 INFO - "product": "firefox", 10:25:16 INFO - "script_repo_revision": "production", 10:25:16 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 10:25:16 INFO - "repository": "", 10:25:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 10:25:16 INFO - "buildid": "20160711143735", 10:25:16 INFO - "stage_platform": "macosx64", 10:25:16 INFO - "basedir": "/builds/slave/test", 10:25:16 INFO - "nightly_build": "True", 10:25:16 INFO - "buildnumber": 37, 10:25:16 INFO - "slavename": "t-yosemite-r7-0147", 10:25:16 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 10:25:16 INFO - "platform": "yosemite_r7", 10:25:16 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:25:16 INFO - "branch": "mozilla-central", 10:25:16 INFO - "repo_path": "mozilla-central", 10:25:16 INFO - "revision": "214884d507ee369c1cf14edb26527c4f9a97bf48", 10:25:16 INFO - "pgo_build": "False", 10:25:16 INFO - "builduid": "a945a57d02a8428eb68bbcc6b19bbba5", 10:25:16 INFO - "slavebuilddir": "test" 10:25:16 INFO - } 10:25:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 10:25:16 INFO - Running post-action listener: _resource_record_post_action 10:25:16 INFO - ##### 10:25:16 INFO - ##### Finished read-buildbot-config step (success) 10:25:16 INFO - ##### 10:25:16 INFO - ##### 10:25:16 INFO - ##### Running download-and-extract step. 10:25:16 INFO - ##### 10:25:16 INFO - Running pre-action listener: _resource_record_pre_action 10:25:16 INFO - Running main action method: download_and_extract 10:25:16 INFO - mkdir: /builds/slave/test/build/tests 10:25: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:25:16 INFO - https://queue.taskcluster.net/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:25:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:25:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:25:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/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:25:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/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:25:40 INFO - Downloaded 1199 bytes. 10:25:40 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 10:25:40 INFO - Using the following test package requirements: 10:25:40 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:25:40 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:25:40 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 10:25:40 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:25:40 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 10:25:40 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:25:40 INFO - u'jsshell-mac.zip'], 10:25:40 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:25:40 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 10:25:40 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:25:40 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:25:40 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 10:25:40 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:25:40 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 10:25:40 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:25:40 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 10:25:40 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:25:40 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 10:25:40 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:25:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:25:40 INFO - https://queue.taskcluster.net/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:25:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:25:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:25:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/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:25:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/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:25:47 INFO - Downloaded 18809557 bytes. 10:25:47 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:25:47 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:25:50 INFO - Return code: 0 10:25:50 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:25:50 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:25:50 INFO - https://queue.taskcluster.net/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:25:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:25:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:25:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/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:25:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/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:25:55 INFO - Downloaded 18809557 bytes. 10:25:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:25:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:25:59 INFO - Return code: 0 10:25:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:25:59 INFO - https://queue.taskcluster.net/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:25:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:25:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:25:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/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:25:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/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:26:00 INFO - Downloaded 14403333 bytes. 10:26:00 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:26:00 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:26:00 INFO - Return code: 0 10:26:00 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:26:00 INFO - https://queue.taskcluster.net/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:26:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:26:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:26:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 10:26:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TCxCaKK0QY-WMjP76UBAZg/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:26:03 INFO - Downloaded 112535332 bytes. 10:26:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:26:03 INFO - mkdir: /builds/slave/test/properties 10:26:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:26:03 INFO - Writing to file /builds/slave/test/properties/build_url 10:26:03 INFO - Contents: 10:26:03 INFO - build_url:https://queue.taskcluster.net/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:26:04 INFO - Running post-action listener: _resource_record_post_action 10:26:04 INFO - Running post-action listener: set_extra_try_arguments 10:26:04 INFO - ##### 10:26:04 INFO - ##### Finished download-and-extract step (success) 10:26:04 INFO - ##### 10:26:04 INFO - ##### 10:26:04 INFO - ##### Running populate-webroot step. 10:26:04 INFO - ##### 10:26:04 INFO - Running pre-action listener: _resource_record_pre_action 10:26:04 INFO - Running main action method: populate_webroot 10:26:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:26:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:26:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:26:04 INFO - '--tppagecycles', 10:26:04 INFO - '7'], 10:26:04 INFO - 'tests': ['tsvgm']}}, 10:26:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:26:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:26:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:26:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:26:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:26:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:26:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:04 INFO - 'talos_options': ['--disable-e10s'], 10:26:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:26:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:26:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:04 INFO - 'talos_options': ['--disable-e10s'], 10:26:04 INFO - 'tests': ['damp', 'tps']}, 10:26:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:04 INFO - 'tests': ['damp', 'tps']}, 10:26:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:26:04 INFO - 'tests': ['dromaeo_dom']}, 10:26:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:26:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:26:04 INFO - 'tests': ['basic_compositor_video']}, 10:26:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:26:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:26:04 INFO - 'tests': ['a11yr', 10:26:04 INFO - 'ts_paint', 10:26:04 INFO - 'tpaint', 10:26:04 INFO - 'sessionrestore', 10:26:04 INFO - 'sessionrestore_no_auto_restore', 10:26:04 INFO - 'tabpaint']}, 10:26:04 INFO - 'other-e10s': {'tests': ['a11yr', 10:26:04 INFO - 'ts_paint', 10:26:04 INFO - 'tpaint', 10:26:04 INFO - 'sessionrestore', 10:26:04 INFO - 'sessionrestore_no_auto_restore', 10:26:04 INFO - 'tabpaint']}, 10:26:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:26:04 INFO - 'ts_paint', 10:26:04 INFO - 'tpaint', 10:26:04 INFO - 'sessionrestore', 10:26:04 INFO - 'sessionrestore_no_auto_restore', 10:26:04 INFO - 'tabpaint']}, 10:26:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:26:04 INFO - 'ts_paint', 10:26:04 INFO - 'tpaint', 10:26:04 INFO - 'sessionrestore', 10:26:04 INFO - 'sessionrestore_no_auto_restore', 10:26:04 INFO - 'tabpaint']}, 10:26:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:26:04 INFO - 'tests': ['a11yr', 10:26:04 INFO - 'ts_paint', 10:26:04 INFO - 'tpaint', 10:26:04 INFO - 'sessionrestore', 10:26:04 INFO - 'sessionrestore_no_auto_restore', 10:26:04 INFO - 'tabpaint']}, 10:26:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:26:04 INFO - 'tests': ['a11yr', 10:26:04 INFO - 'ts_paint', 10:26:04 INFO - 'tpaint', 10:26:04 INFO - 'sessionrestore', 10:26:04 INFO - 'sessionrestore_no_auto_restore', 10:26:04 INFO - 'tabpaint']}, 10:26:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:26:04 INFO - 'tests': ['tsvgx', 10:26:04 INFO - 'tsvgr_opacity', 10:26:04 INFO - 'tart', 10:26:04 INFO - 'tscrollx', 10:26:04 INFO - 'cart']}, 10:26:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:26:04 INFO - 'tsvgr_opacity', 10:26:04 INFO - 'tart', 10:26:04 INFO - 'tscrollx', 10:26:04 INFO - 'cart']}, 10:26:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:04 INFO - 'talos_options': ['--disable-e10s'], 10:26:04 INFO - 'tests': ['tp5o']}, 10:26:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:04 INFO - 'tests': ['tp5o']}, 10:26:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:04 INFO - 'talos_options': ['--disable-e10s', 10:26:04 INFO - '--xperf_path', 10:26:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:26:04 INFO - 'tests': ['tp5n']}, 10:26:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:04 INFO - 'talos_options': ['--xperf_path', 10:26:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:26:04 INFO - 'tests': ['tp5n']}}, 10:26:04 INFO - 'talos.zip': {'path': '', 10:26:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:26:04 INFO - Running post-action listener: _resource_record_post_action 10:26:04 INFO - ##### 10:26:04 INFO - ##### Finished populate-webroot step (success) 10:26:04 INFO - ##### 10:26:04 INFO - ##### 10:26:04 INFO - ##### Running create-virtualenv step. 10:26:04 INFO - ##### 10:26:04 INFO - Running pre-action listener: _resource_record_pre_action 10:26:04 INFO - Running main action method: create_virtualenv 10:26:04 INFO - Creating virtualenv /builds/slave/test/build/venv 10:26:04 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:26:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:26:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:26:05 INFO - Using real prefix '/tools/python27' 10:26:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:26:05 INFO - Installing distribute.............................................................................................................................................................................................done. 10:26:08 INFO - Installing pip.................done. 10:26:08 INFO - Return code: 0 10:26:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:08 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 0x1043cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104461618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86c3ca6440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104330d30>, '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 0x104095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104328b20>, '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.sGcDOxx4Ep/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.7UK0VQaA24/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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:26:08 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:26:08 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:26:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGcDOxx4Ep/Render', 10:26:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:08 INFO - 'HOME': '/Users/cltbld', 10:26:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:08 INFO - 'LOGNAME': 'cltbld', 10:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:08 INFO - 'MOZ_NO_REMOTE': '1', 10:26:08 INFO - 'NO_EM_RESTART': '1', 10:26:08 INFO - 'PAGER': '/bin/cat', 10:26:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:08 INFO - 'PWD': '/builds/slave/test', 10:26:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:08 INFO - 'SHELL': '/bin/bash', 10:26:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7UK0VQaA24/Listeners', 10:26:08 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:26:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:08 INFO - 'USER': 'cltbld', 10:26:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:08 INFO - 'XPC_FLAGS': '0x0', 10:26:08 INFO - 'XPC_SERVICE_NAME': '0', 10:26:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:08 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:26:08 INFO - Cleaning up... 10:26:08 INFO - Return code: 0 10:26:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:08 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 0x1043cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104461618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86c3ca6440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104330d30>, '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 0x104095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104328b20>, '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.sGcDOxx4Ep/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.7UK0VQaA24/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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:26:08 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:26:08 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:26:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGcDOxx4Ep/Render', 10:26:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:08 INFO - 'HOME': '/Users/cltbld', 10:26:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:08 INFO - 'LOGNAME': 'cltbld', 10:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:08 INFO - 'MOZ_NO_REMOTE': '1', 10:26:08 INFO - 'NO_EM_RESTART': '1', 10:26:08 INFO - 'PAGER': '/bin/cat', 10:26:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:08 INFO - 'PWD': '/builds/slave/test', 10:26:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:08 INFO - 'SHELL': '/bin/bash', 10:26:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7UK0VQaA24/Listeners', 10:26:08 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:26:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:08 INFO - 'USER': 'cltbld', 10:26:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:08 INFO - 'XPC_FLAGS': '0x0', 10:26:08 INFO - 'XPC_SERVICE_NAME': '0', 10:26:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:09 INFO - Downloading/unpacking psutil>=3.1.1 10:26:09 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:26:09 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:26:09 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:26:09 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:26:09 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:26:09 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:26:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:26:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:26:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:26:12 INFO - Installing collected packages: psutil 10:26:12 INFO - Running setup.py install for psutil 10:26:12 INFO - building 'psutil._psutil_osx' extension 10:26:12 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:26:12 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:26:12 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:26:12 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:26:13 INFO - building 'psutil._psutil_posix' extension 10:26:13 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:26:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:26:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:26:13 INFO - ^ 10:26:13 INFO - 1 warning generated. 10:26:13 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:26:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:26:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:26:13 INFO - Successfully installed psutil 10:26:13 INFO - Cleaning up... 10:26:13 INFO - Return code: 0 10:26:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:26:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1043cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104461618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86c3ca6440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104330d30>, '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 0x104095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104328b20>, '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.sGcDOxx4Ep/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.7UK0VQaA24/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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:26:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:26:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:26:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGcDOxx4Ep/Render', 10:26:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:13 INFO - 'HOME': '/Users/cltbld', 10:26:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:13 INFO - 'LOGNAME': 'cltbld', 10:26:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:13 INFO - 'MOZ_NO_REMOTE': '1', 10:26:13 INFO - 'NO_EM_RESTART': '1', 10:26:13 INFO - 'PAGER': '/bin/cat', 10:26:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:13 INFO - 'PWD': '/builds/slave/test', 10:26:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:13 INFO - 'SHELL': '/bin/bash', 10:26:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7UK0VQaA24/Listeners', 10:26:13 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:26:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:13 INFO - 'USER': 'cltbld', 10:26:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:13 INFO - 'XPC_FLAGS': '0x0', 10:26:13 INFO - 'XPC_SERVICE_NAME': '0', 10:26:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:26:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:26:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:26:16 INFO - Installing collected packages: mozsystemmonitor 10:26:16 INFO - Running setup.py install for mozsystemmonitor 10:26:16 INFO - Successfully installed mozsystemmonitor 10:26:16 INFO - Cleaning up... 10:26:16 INFO - Return code: 0 10:26:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:26: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:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26: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:26:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:16 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 0x1043cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104461618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86c3ca6440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104330d30>, '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 0x104095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104328b20>, '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.sGcDOxx4Ep/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.7UK0VQaA24/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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:26:16 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:26:16 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:26:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGcDOxx4Ep/Render', 10:26:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:16 INFO - 'HOME': '/Users/cltbld', 10:26:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:16 INFO - 'LOGNAME': 'cltbld', 10:26:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:16 INFO - 'MOZ_NO_REMOTE': '1', 10:26:16 INFO - 'NO_EM_RESTART': '1', 10:26:16 INFO - 'PAGER': '/bin/cat', 10:26:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:16 INFO - 'PWD': '/builds/slave/test', 10:26:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:16 INFO - 'SHELL': '/bin/bash', 10:26:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7UK0VQaA24/Listeners', 10:26:16 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:26:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:16 INFO - 'USER': 'cltbld', 10:26:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:16 INFO - 'XPC_FLAGS': '0x0', 10:26:16 INFO - 'XPC_SERVICE_NAME': '0', 10:26:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:16 INFO - Downloading/unpacking jsonschema==2.5.1 10:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:26:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:26:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:19 INFO - Downloading functools32-3.2.3-2.tar.gz 10:26:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:26:19 INFO - warning: no files found matching '*.txt' 10:26:19 INFO - no previously-included directories found matching 'build' 10:26:19 INFO - no previously-included directories found matching 'dist' 10:26:19 INFO - no previously-included directories found matching '.git*' 10:26:19 INFO - Installing collected packages: jsonschema, functools32 10:26:19 INFO - Running setup.py install for functools32 10:26:20 INFO - warning: no files found matching '*.txt' 10:26:20 INFO - no previously-included directories found matching 'build' 10:26:20 INFO - no previously-included directories found matching 'dist' 10:26:20 INFO - no previously-included directories found matching '.git*' 10:26:20 INFO - Successfully installed jsonschema functools32 10:26:20 INFO - Cleaning up... 10:26:20 INFO - Return code: 0 10:26:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:26:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1043cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104461618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86c3ca6440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104330d30>, '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 0x104095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104328b20>, '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.sGcDOxx4Ep/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.7UK0VQaA24/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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:26:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:26:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:26:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGcDOxx4Ep/Render', 10:26:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:20 INFO - 'HOME': '/Users/cltbld', 10:26:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:20 INFO - 'LOGNAME': 'cltbld', 10:26:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:20 INFO - 'MOZ_NO_REMOTE': '1', 10:26:20 INFO - 'NO_EM_RESTART': '1', 10:26:20 INFO - 'PAGER': '/bin/cat', 10:26:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:20 INFO - 'PWD': '/builds/slave/test', 10:26:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:20 INFO - 'SHELL': '/bin/bash', 10:26:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7UK0VQaA24/Listeners', 10:26:20 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:26:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:20 INFO - 'USER': 'cltbld', 10:26:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:20 INFO - 'XPC_FLAGS': '0x0', 10:26:20 INFO - 'XPC_SERVICE_NAME': '0', 10:26:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:20 INFO - Downloading/unpacking blobuploader==1.2.4 10:26:20 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:26:20 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:26:20 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:26:20 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:26:20 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:26:20 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:26:22 INFO - Downloading blobuploader-1.2.4.tar.gz 10:26:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:26:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:26:23 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:26:23 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:26:23 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:26:23 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:26:23 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:26:23 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:26:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:26:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:26:23 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:26:23 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:26:23 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:26:23 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:26:23 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:26:23 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:26:24 INFO - Downloading docopt-0.6.1.tar.gz 10:26:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:26:24 INFO - Installing collected packages: blobuploader, requests, docopt 10:26:24 INFO - Running setup.py install for blobuploader 10:26:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:26:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:26:24 INFO - Running setup.py install for requests 10:26:24 INFO - Running setup.py install for docopt 10:26:25 INFO - Successfully installed blobuploader requests docopt 10:26:25 INFO - Cleaning up... 10:26:25 INFO - Return code: 0 10:26:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:25 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 0x1043cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104461618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86c3ca6440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104330d30>, '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 0x104095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104328b20>, '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.sGcDOxx4Ep/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.7UK0VQaA24/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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:26:25 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:26:25 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:26:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGcDOxx4Ep/Render', 10:26:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:25 INFO - 'HOME': '/Users/cltbld', 10:26:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:25 INFO - 'LOGNAME': 'cltbld', 10:26:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:25 INFO - 'MOZ_NO_REMOTE': '1', 10:26:25 INFO - 'NO_EM_RESTART': '1', 10:26:25 INFO - 'PAGER': '/bin/cat', 10:26:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:25 INFO - 'PWD': '/builds/slave/test', 10:26:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:25 INFO - 'SHELL': '/bin/bash', 10:26:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7UK0VQaA24/Listeners', 10:26:25 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:26:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:25 INFO - 'USER': 'cltbld', 10:26:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:25 INFO - 'XPC_FLAGS': '0x0', 10:26:25 INFO - 'XPC_SERVICE_NAME': '0', 10:26:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:26:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-PFr0FS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:26:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-c6QMfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:26:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-ejSQn7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:26:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-zE1nHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:26:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-_oprTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:26:25 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-5tfJuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:26:26 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-zSl_Tc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:26:26 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-qrMc4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:26:26 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-PUjyGa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:26:26 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-kWOZF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:26:26 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-HOoTPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:26:26 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-T3AG31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:26:26 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-xrpA29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:26:26 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-MgxZV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:26:26 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-kjx5om-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:26:26 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-YAzRQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:26:27 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-uLW2JB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:26:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:26:27 INFO - Running setup.py install for manifestparser 10:26:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:26:27 INFO - Running setup.py install for mozcrash 10:26:27 INFO - Running setup.py install for mozdebug 10:26:27 INFO - Running setup.py install for mozdevice 10:26:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:26:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:26:27 INFO - Running setup.py install for mozfile 10:26:27 INFO - Running setup.py install for mozhttpd 10:26:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:26:28 INFO - Running setup.py install for mozinfo 10:26:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:26:28 INFO - Running setup.py install for mozInstall 10:26:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:26:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:26:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:26:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:26:28 INFO - Running setup.py install for mozleak 10:26:28 INFO - Running setup.py install for mozlog 10:26:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:26:28 INFO - Running setup.py install for moznetwork 10:26:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:26:28 INFO - Running setup.py install for mozprocess 10:26:28 INFO - Running setup.py install for mozprofile 10:26:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:26:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:26:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:26:29 INFO - Running setup.py install for mozrunner 10:26:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:26:29 INFO - Running setup.py install for mozscreenshot 10:26:29 INFO - Running setup.py install for moztest 10:26:29 INFO - Running setup.py install for mozversion 10:26:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:26:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:26:29 INFO - Cleaning up... 10:26:29 INFO - Return code: 0 10:26:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:26: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:26:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26: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:26:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:29 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 0x1043cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104461618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86c3ca6440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104330d30>, '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 0x104095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104328b20>, '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.sGcDOxx4Ep/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.7UK0VQaA24/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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:26:29 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:26:29 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:26:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGcDOxx4Ep/Render', 10:26:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:29 INFO - 'HOME': '/Users/cltbld', 10:26:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:29 INFO - 'LOGNAME': 'cltbld', 10:26:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:29 INFO - 'MOZ_NO_REMOTE': '1', 10:26:29 INFO - 'NO_EM_RESTART': '1', 10:26:29 INFO - 'PAGER': '/bin/cat', 10:26:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:29 INFO - 'PWD': '/builds/slave/test', 10:26:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:29 INFO - 'SHELL': '/bin/bash', 10:26:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7UK0VQaA24/Listeners', 10:26:29 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:26:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:29 INFO - 'USER': 'cltbld', 10:26:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:29 INFO - 'XPC_FLAGS': '0x0', 10:26:29 INFO - 'XPC_SERVICE_NAME': '0', 10:26:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:26:30 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-xgzzLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:26:30 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:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:26:30 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-nKeLf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:26:30 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:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:26:30 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-kb9F2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:26:30 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:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:26:30 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-_nwtcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:26:30 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:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:26:30 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-6U5qcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:26:30 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:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:26:30 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-ssNfc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:26:30 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:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:26:30 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-H06B2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:26:30 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:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:26:30 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-Wetg41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:26:30 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:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:26:30 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-OBxBdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:26:30 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:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:26:30 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-EADDQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:26:31 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:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:26:31 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-3dlPd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:26:31 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:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:26:31 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-Mtegz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:26:31 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:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:26:31 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-NjSHX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:26:31 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:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:26:31 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-YWrrEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:26:31 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:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:26:31 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-jhr0KL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:26:31 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:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:26:31 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-4bA9aB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:26:31 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:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:26:31 INFO - Running setup.py (path:/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/pip-vR4BIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:26:31 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:26:31 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:26:31 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:26:31 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:26:31 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:26:31 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:26:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:26:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:34 INFO - Downloading blessings-1.6.tar.gz 10:26:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:26:34 INFO - Installing collected packages: blessings 10:26:34 INFO - Running setup.py install for blessings 10:26:34 INFO - Successfully installed blessings 10:26:34 INFO - Cleaning up... 10:26:34 INFO - Return code: 0 10:26:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:26:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:26:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:26:35 INFO - Reading from file tmpfile_stdout 10:26:35 INFO - Current package versions: 10:26:35 INFO - blessings == 1.6 10:26:35 INFO - blobuploader == 1.2.4 10:26:35 INFO - docopt == 0.6.1 10:26:35 INFO - functools32 == 3.2.3-2 10:26:35 INFO - jsonschema == 2.5.1 10:26:35 INFO - manifestparser == 1.1 10:26:35 INFO - mozInstall == 1.12 10:26:35 INFO - mozcrash == 0.17 10:26:35 INFO - mozdebug == 0.1 10:26:35 INFO - mozdevice == 0.48 10:26:35 INFO - mozfile == 1.2 10:26:35 INFO - mozhttpd == 0.7 10:26:35 INFO - mozinfo == 0.9 10:26:35 INFO - mozleak == 0.1 10:26:35 INFO - mozlog == 3.2 10:26:35 INFO - moznetwork == 0.27 10:26:35 INFO - mozprocess == 0.23 10:26:35 INFO - mozprofile == 0.28 10:26:35 INFO - mozrunner == 6.12 10:26:35 INFO - mozscreenshot == 0.1 10:26:35 INFO - mozsystemmonitor == 0.3 10:26:35 INFO - moztest == 0.7 10:26:35 INFO - mozversion == 1.4 10:26:35 INFO - psutil == 3.1.1 10:26:35 INFO - requests == 1.2.3 10:26:35 INFO - wsgiref == 0.1.2 10:26:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:35 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 0x1043cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104461618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86c3ca6440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104330d30>, '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 0x104095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104328b20>, '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.sGcDOxx4Ep/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.7UK0VQaA24/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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:26:35 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:26:35 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:26:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGcDOxx4Ep/Render', 10:26:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:35 INFO - 'HOME': '/Users/cltbld', 10:26:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:35 INFO - 'LOGNAME': 'cltbld', 10:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:35 INFO - 'MOZ_NO_REMOTE': '1', 10:26:35 INFO - 'NO_EM_RESTART': '1', 10:26:35 INFO - 'PAGER': '/bin/cat', 10:26:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:35 INFO - 'PWD': '/builds/slave/test', 10:26:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:35 INFO - 'SHELL': '/bin/bash', 10:26:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7UK0VQaA24/Listeners', 10:26:35 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:26:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:35 INFO - 'USER': 'cltbld', 10:26:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:35 INFO - 'XPC_FLAGS': '0x0', 10:26:35 INFO - 'XPC_SERVICE_NAME': '0', 10:26:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:35 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:26:35 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:26:35 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:26:35 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:26:35 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:26:35 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:26:35 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:26:35 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:26:35 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:26:35 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:26:35 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:26:35 INFO - Cleaning up... 10:26:35 INFO - Return code: 0 10:26:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:35 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 0x1043cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104461618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f86c3ca6440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104330d30>, '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 0x104095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104328b20>, '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.sGcDOxx4Ep/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.7UK0VQaA24/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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:26:35 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:26:35 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:26:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGcDOxx4Ep/Render', 10:26:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:35 INFO - 'HOME': '/Users/cltbld', 10:26:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:35 INFO - 'LOGNAME': 'cltbld', 10:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:35 INFO - 'MOZ_NO_REMOTE': '1', 10:26:35 INFO - 'NO_EM_RESTART': '1', 10:26:35 INFO - 'PAGER': '/bin/cat', 10:26:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:35 INFO - 'PWD': '/builds/slave/test', 10:26:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:35 INFO - 'SHELL': '/bin/bash', 10:26:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7UK0VQaA24/Listeners', 10:26:35 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:26:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:35 INFO - 'USER': 'cltbld', 10:26:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:35 INFO - 'XPC_FLAGS': '0x0', 10:26:35 INFO - 'XPC_SERVICE_NAME': '0', 10:26:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:26:35 INFO - Cleaning up... 10:26:35 INFO - Return code: 0 10:26:35 INFO - Running post-action listener: _resource_record_post_action 10:26:35 INFO - Running post-action listener: _start_resource_monitoring 10:26:35 INFO - Starting resource monitoring. 10:26:35 INFO - ##### 10:26:35 INFO - ##### Finished create-virtualenv step (success) 10:26:35 INFO - ##### 10:26:35 INFO - ##### 10:26:35 INFO - ##### Running install step. 10:26:35 INFO - ##### 10:26:35 INFO - Running pre-action listener: _resource_record_pre_action 10:26:35 INFO - Running main action method: install 10:26:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:26:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:26:35 INFO - Reading from file tmpfile_stdout 10:26:35 INFO - Detecting whether we're running mozinstall >=1.0... 10:26:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:26:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:26:36 INFO - Reading from file tmpfile_stdout 10:26:36 INFO - Output received: 10:26:36 INFO - Usage: mozinstall [options] installer 10:26:36 INFO - Options: 10:26:36 INFO - -h, --help show this help message and exit 10:26:36 INFO - -d DEST, --destination=DEST 10:26:36 INFO - Directory to install application into. [default: 10:26:36 INFO - "/builds/slave/test"] 10:26:36 INFO - --app=APP Application being installed. [default: firefox] 10:26:36 INFO - mkdir: /builds/slave/test/build/application 10:26:36 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:26:36 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:27:10 INFO - Reading from file tmpfile_stdout 10:27:10 INFO - Output received: 10:27:10 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 10:27:10 INFO - Running post-action listener: _resource_record_post_action 10:27:10 INFO - ##### 10:27:10 INFO - ##### Finished install step (success) 10:27:10 INFO - ##### 10:27:10 INFO - ##### 10:27:10 INFO - ##### Running run-tests step. 10:27:10 INFO - ##### 10:27:10 INFO - Running pre-action listener: _resource_record_pre_action 10:27:10 INFO - Running main action method: run_tests 10:27:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:27:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:27:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:27:10 INFO - 2016-07-11 10:27:10.823 screenresolution[1647:7597] starting screenresolution argv=screenresolution get 10:27:10 INFO - 2016-07-11 10:27:10.826 screenresolution[1647:7597] Display 0: 1600x1200x32@60 10:27:10 INFO - 2016-07-11 10:27:10.832 screenresolution[1648:7599] starting screenresolution argv=screenresolution list 10:27:10 INFO - Available Modes on Display 0 10:27:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:27:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:27:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:27:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:27:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:27:11 INFO - Intel Iris: 10:27:11 INFO - Chipset Model: Intel Iris 10:27:11 INFO - Type: GPU 10:27:11 INFO - Bus: Built-In 10:27:11 INFO - VRAM (Dynamic, Max): 1536 MB 10:27:11 INFO - Vendor: Intel (0x8086) 10:27:11 INFO - Device ID: 0x0a2e 10:27:11 INFO - Revision ID: 0x0009 10:27:11 INFO - Displays: 10:27:11 INFO - Display: 10:27:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:27:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:27:11 INFO - Display Serial Number: 1600x1200 60 10:27:11 INFO - Main Display: Yes 10:27:11 INFO - Mirror: Off 10:27:11 INFO - Online: Yes 10:27:11 INFO - Rotation: Supported 10:27:11 INFO - Return code: 0 10:27:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:27:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:27:11 INFO - Python 2.7.3 10:27:11 INFO - Return code: 0 10:27:11 INFO - grabbing minidump binary from tooltool 10:27:11 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:27:11 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 0x104330d30>, '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 0x104095db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104328b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:27:11 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:27:11 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:27:11 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:27:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:27:11 INFO - Return code: 0 10:27:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:27:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:27:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:27:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:27:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:27:11 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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:27:11 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0147 --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:27:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sGcDOxx4Ep/Render', 10:27:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:11 INFO - 'HOME': '/Users/cltbld', 10:27:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:11 INFO - 'LOGNAME': 'cltbld', 10:27:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:27:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:27:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:11 INFO - 'MOZ_NO_REMOTE': '1', 10:27:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:27:11 INFO - 'NO_EM_RESTART': '1', 10:27:11 INFO - 'PAGER': '/bin/cat', 10:27:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:11 INFO - 'PWD': '/builds/slave/test', 10:27:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:27:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:11 INFO - 'SHELL': '/bin/bash', 10:27:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7UK0VQaA24/Listeners', 10:27:11 INFO - 'TMPDIR': '/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/', 10:27:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:11 INFO - 'USER': 'cltbld', 10:27:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:11 INFO - 'XPC_FLAGS': '0x0', 10:27:11 INFO - 'XPC_SERVICE_NAME': '0', 10:27:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:11 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0147', '--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:27:11 INFO - mozversion application_buildid: 20160711143735 10:27:11 INFO - mozversion application_changeset: 214884d507ee369c1cf14edb26527c4f9a97bf48 10:27:11 INFO - mozversion application_display_name: Nightly 10:27:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:27:11 INFO - mozversion application_name: Firefox 10:27:11 INFO - mozversion application_remotingname: firefox 10:27:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:27:11 INFO - mozversion application_vendor: Mozilla 10:27:11 INFO - mozversion application_version: 50.0a1 10:27:11 INFO - mozversion platform_buildid: 20160711143735 10:27:11 INFO - mozversion platform_changeset: 214884d507ee369c1cf14edb26527c4f9a97bf48 10:27:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:27:11 INFO - mozversion platform_version: 50.0a1 10:27:11 INFO - using testdate: 1468258031 10:27:11 INFO - actual date: 1468258031 10:27:11 INFO - starting webserver on 'localhost:49276' 10:27:11 INFO - SUITE-START | Running 2 tests 10:27:11 INFO - TEST-START | dromaeo_css 10:27:11 INFO - operating with platform_type : mac_ 10:27:11 INFO - Initialising browser for dromaeo_css test... 10:27:11 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpgB19Qb/profile http://localhost:49276/getInfo.html) 10:27:14 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 10:27:14 INFO - PROCESS | 1658 | colorDepth:24 10:27:14 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 10:27:14 INFO - PROCESS | 1658 | __metrics 10:27:14 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" 10:27:14 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] 10:27:14 INFO - TEST-INFO | 1658: exit 0 10:27:14 INFO - Browser initialized. 10:27:14 INFO - Running cycle 1/1 for dromaeo_css test... 10:27:14 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmpgB19Qb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:27:26 INFO - PROCESS | 1661 | RSS: Main: 243564544 10:27:26 INFO - PROCESS | 1661 | 10:28:18 INFO - PROCESS | 1661 | 1468258098509 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:30:29 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 10:30:30 INFO - PROCESS | 1661 | RSS: Main: 336420864 10:30:30 INFO - PROCESS | 1661 | 10:33:33 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 10:33:34 INFO - PROCESS | 1661 | RSS: Main: 374407168 10:33:34 INFO - PROCESS | 1661 | 10:36:36 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 10:36:37 INFO - PROCESS | 1661 | RSS: Main: 369836032 10:36:37 INFO - PROCESS | 1661 | 10:39:39 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 10:39:40 INFO - PROCESS | 1661 | RSS: Main: 371048448 10:39:40 INFO - PROCESS | 1661 | 10:42:43 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 10:42:44 INFO - PROCESS | 1661 | RSS: Main: 336412672 10:42:44 INFO - PROCESS | 1661 | 10:45:47 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 10:45:47 INFO - PROCESS | 1661 | RSS: Main: 386035712 10:45:47 INFO - PROCESS | 1661 | 10:45:47 INFO - PROCESS | 1661 | __start_tp_report 10:45:47 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 10:45:47 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 10:45:47 INFO - PROCESS | 1661 | |i|pagename|runs| 10:45:47 INFO - PROCESS | 1661 | |0;dojo.html;3301.669833016698;3303.735701143907;3328.0015995201443;3371.0898057524455;3383.0865666270106;5597.748101335439;5668.659880407178;5690.5732070094755;5805.100209467529;5823.708814559276;5702.258706368177;5705.942940570603;5812.3025236971625;5836.38717934616;5882.14708867214;5155.999999999981;5162.096633089213;5219.973900130494;5220.216967454893;5353.9196912046355;5309.203619457093;5339.41266460689;5342.198670199462;5353.946460535384;5397.7031263280505;5134.845954621367;5201.843944681666;5217.113090774539;5259.237410575483;5272.235525848769;5193.195054766517;5208.687478751249;5250.842474725726;5268.262443257947;5282.973585132088;5168.457311982225;5171.405288391846;5184.792608295701;5214.191800270964;5224.111900976843;5694.857628559319;5761.078227483625;5776.451237132508;5798.043322851724;5800.680962547058;6123.846903827395;6162.815115546541;6227.875442491125;6292.811215663537;6303.149074874879;6247.687615619201;6257.311695713468;6257.906131407987;6265.592736472115;6273.686315684243;5854.882902341929;5858.209141765807;5902.291724993029;5904.439078287556;5920.023196172583;6143.266246520345;6216.316205217422;6252.030935205051;6263.874722505524;6284.497240220771;6192.195014648067;6198.039303907902;6202.4417802398;6202.906956395657;6205.720742566596;6288.182536270256;6291.591046581958;6296.370362963667;6298.811035668938;6303.959846625347;6103.1455596216565;6116.853194528077;6137.376985335611;6142.170806941095;6196.287426945869;6161.599496032743;6186.319504854462;6231.563790534619;6304.211973503312;6318.399752023551;4933.605311575066;4943.703377797344;4951.257311403247;4960.429550601655;4984.850454486371;4340.674449416307;4454.710443821141;4462.620677242406;4464.30803225494;4499.865004049884;5571.220029195835;5721.656700597978;5730.36965933747;5737.971310143423;5741.483266505951;6186.257649082139;6254.781082662085;6274.560780745304;6288.52836037299;6301.495880329563;5207.765650545734;5485.835424937259;5763.279590051244;5764.221830052889;5782.132680097935;5308.46915308466;5380.112281473513;5422.674639521641;5439.048166570834;5441.374241962146;5679.195721413481;6233.470155036873;6353.3011368748475;6363.3636636336;6376.904346434714;6252.937470625236;6293.685315734104;6296.118543403835;6308.369163083655;6318.778842740482;5407.378151512627;6136.457808108256;6227.564070515021;6236.781712640036;6346.873062538631;6222.875542489215;6244.656543889948;6257.499400047895;6257.5932564383165;6265.874682506415;6073.392660733891;6280.246370435577;6292.2134733158355;6297.401746834226;6298;5971.373005834324;6055.182550355822;6115.877682446415;6132.8773424532155;6161.137440758387;5180.0157969985585;6137.91625765497;6184.196054512884;6188.44304012641;6191.226096737908;6040.456358927718;6040.939590604038;6057.569730008609;6067.67447169642;6078;5948.524118070631;6048.546359023003;6087.05650624154;6090.9390906090375;6132.38676132401;6012.549058820693;6021.006533921854;6030.6080104793045;6038.788642397495;6048.455638992512 10:45:47 INFO - PROCESS | 1661 | |1;ext.html;473.8767920340711;508.0499465998581;515.9896802063959;519.0941806547573;534.893021395721;29460;30512.779488820448;31505.68494315056;31553.05340839773;31563.84218078912;31985.680143198562;41658;42597.574024259746;42850.35724464135;45145.548544514546;9555.617775288998;9702.369345992487;9705.90294097057;9706.61173553057;9718.271129665269;8628.309735221168;9670.032996700344;9776.608935642602;9776.680148180012;9795.314327997043;8624.741257762245;8910;8927.687530936419;8981.595828187714;11749.7650046999;11815.881841181606;12094.334811585359;12112.273263604167;12113;12133;11911.404429778477;11983.700407489883;11990.520379184822;12024;12024.639260822189;31993.84003079993;40105.79947100246;40197.99505012369;40948.97627559305;41181;3435.914102147441;3679.8528058877614;3879.9224015519812;4133.2973394522705;4408.955910440886;31438.842805786055;31660.683393166233;32293.354132917444;33481.33037339264;33598.83200583982;3214.260720034405;3324.1191084362663;3350.163586084988;3420.4185288500767;3424.989628059728;514967;516649;519094;519484.9999999962;519942;53832.19251711227;62563.68718156471;63667;63681.726365473354;63966.72066558736;67665;67678.66160669264;67896;67960.3203967954;68133.29666758371;16626.83373166277;16899.32402703878;16918.661626767644;16932.984020958782;16936.98378097318;10964.945175274232;12989.4154763034;12999.350032498338;13003.544875929487;13019.47922083125;16551.586210344838;19701;19710.211591536463;19742.802571974386;19774.802251977297;6778.457723382117;8049.6377663005305;8167.079801695219;8354.707585234488;8479.406441549032;884.6859364925488;981.7202097402206;1024.784795193003;1097.1935627313917;1138.1975707126455;30264.69735302663;30366.696333036827;30747.69252307493;30801;30936.535951960737;1057.1859668055554;1061.692109288313;1069.561479793281;1085.6688709943544;1121.3552207480732;13213.73572528544;15916.840831591768;15917.442889498812;16380.590485237726;16390.26243819031;617648;618608;619176;623727.8813605903;628254;52035.47964520382;58990.41009589935;60206;60765;60786;1412.5268035208114;1431.4131206205407;1443.076431084107;1449.6303442622063;1449.6303442622275;26698.332541686614;31096.22259443532;31712;31757.36485270328;32411.837940810146;52303.476965229864;52437.68905777386;52564.73717631387;52735.736321318145;52741;565651;575117;575150;624454;815934;25686.486270274116;26627.866860665574;26782.330441739112;26783.464330712886;26800.061997829984;46419.76790116028;47328;47689.28466072942;48102.759486203744;48309.51690483191;38271.6172838268;38746;39183.80408097941;39808.2038359237;39847.203055939295;38285.042873928374;38505.614943851324;38800.80599596984;39584.81245562638;40515.797421013885;36774.26451471009;37605;38063.61936380711;38158;38203.42694859635;26511.602325965512;34172.65827341695;34256;34358;34386.484202737476;24787.50424991454;24942;25021.238318924883;25129.623055653814;25686.229413117635 10:45:47 INFO - PROCESS | 1661 | |2;jquery.html;313.61582061974076;349.70449969775564;367.40847235950116;375.32628931068746;400.41939188177133;15641.608959775996;17609.911950440244;20523.589528209464;21263.36209913701;21272.8936355318;21128;21601.351959441203;21950;21961.121555137815;22117.33647990559;5303.734813259341;5609.551235901128;6043.728032238538;6103;6108.572399931984;5940.6435613863305;6000.699965001754;6039.305479869828;6049.879002419949;6061.787837425691;5754.16564598133;5980.401959804007;5996.97001514994;6006.579539432241;6010.459058684717;5913.438223368773;5930.614510056854;5967.015442451991;5967.671778052227;5967.761289548413;5104.157813960692;5408.91886621701;5412.945870541323;5426.538744206747;5427.511523962862;12982;13306.86693133066;13447.66380840477;18044.27822887083;25703.61444578333;2299.4251437140715;2462.7783499484954;2589.624588997824;2638.511875303075;2958.8921079617285;9928.503574821303;9975.90024099757;10063.04401081896;10302.330348527397;10330.948345258226;1757.261949981011;1800.8469280111076;1833.1384249402759;1839.3194518028415;1852.7128295114144;81692;113513;114872.42563787212;117374.82625173725;122033.38983305116;9401.623935042522;10911.399873007062;11040.944795276133;11272.267302625303;11290.579407257106;14198.219097949504;14241.85758142426;14264.786028209584;14320.641983950485;14333.498327558485;8495.575221238912;8642.43824151428;8679;8770.166834150745;8824.470531767985;8060.395470339748;8556.203197328507;8557.400981931205;8667.089955554577;8672.60973256205;4391.165678521071;4441.267190913463;4471.7987690554;4500.437445319335;4563.703359281636;1756.1833747307307;1872.7939926608062;1897.3074827687794;1898.3071179019557;1907.9523011924534;774.8605251054864;957.7788319892193;1011.4942528735633;1018.7962407518526;1076.4133547216786;19187.040647967548;20336.593268134555;20405;21535.35393938153;21582.460438489165;966.5505539923829;982.5627595719836;1010.2726037253166;1032.726327523207;1045.2265323660436;9206.539673016323;10760.19298552596;11039.944800276107;11235.83146252807;11294.60468883585;512875;558446;568121;571874;575115;74586;95936;115068.42465787785;124310.75689242991;147887.26056369866;914.7758799094131;938.5072836760619;949.392388871123;951.0394501553343;973.8295798235139;18823.623527529646;18935.242590296235;19060.523486912938;19148.617027659646;19346.8065319345;51012.489875102256;52640.736796315774;53409;60685;61743;74842;77327.22672773425;79961.2003879977;83452.58273708592;86107;7747.18654541265;7997.880031799644;8038;8054.8791768122355;8227.753167404988;12913.677158071123;12933.870661293267;13006.739865202453;13249.47002119943;13411.262380569162;11272.985431310894;11416.657500275005;11548.018418434527;11692.94153529227;11698.76602467963;10269.589216431557;11190.944045280046;11328.207025508136;11381.089512839118;11609.071274297867;11098.901098901099;11422.31466112036;11868.05055595533;11906.642800715992;11933.701657458632;8314.459560128573;8366.790830229293;8381.916180838114;8386.077531471412;8534.146585341416;11083.115870301906;11576.536938522364;11743.530258789553;11783.646490605295;11910.940445297718 10:45:47 INFO - PROCESS | 1661 | |3;mootools.html;1569.8744100471963;1600.4398460538812;1802.9819701802978;1920.3278852401652;1935.303290815307;6950.582965022089;8564.443311184776;9035.548222588877;9050.456972581633;9061.864072038916;7794.259545343198;8030.598470076502;8036.8392632147325;8184;8758.649654013847;2144.206643541888;2285.394370491813;2369.18263199196;2382.809375249976;2391.222852572912;2104.4528422610156;2340.8880781628677;2370.525894821034;2388.7014123234594;2389.330987323552;2087.1025459052603;2366.715994080713;2367.1241640593003;2370.9288721338303;2373.038919237703;2271.977280227193;2345.7185137783404;2348.2250857217164;2352.1061996441413;2352.5765362234793;2328.4877326988035;2344.542814151241;2349.6475528670667;2351.318117745852;2361.2089949866813;7671;7757.456978011542;8891;8924.955375223082;8925.821483570293;5197.298364720752;5359.410464848864;5367.2485851980755;5367.543758780508;5367.597430192712;3439.8280085995602;3519.3841077811535;3581.3732596795717;3591.856325746993;3595.7303202259936;1048.5124417146064;1062.9946850265699;1070.4433694478905;1085.6580177244143;1088.9128869690405;11714.88285117155;11764.647060588197;11765.705857353549;11771.234869733526;11813.940930295379;4461.420015397977;4655.534446555317;4730.219513780256;4741.644376671763;4750.334953106568;8523.531205783742;9781.90218097824;9895.80208395828;9942.353747006422;9956.20350371983;5341.145416733304;5341.946580534222;5345.4654534546235;5351.973240133774;5352.19717042439;3952.407138929126;4420.84527041552;4907.141250281186;4921.286413470027;4928.211486162197;3527.065327688165;3698.68561172298;3768.057985503624;3801.2207497462955;3806.5432148141845;1134.733337665648;1138.1407037686658;1148.357848276972;1148.6439203846835;1153.6827372472637;953.6519170502717;958.3100167879115;972.3436680240809;988.2094324540338;1004.5278719002057;5949.791757288561;5964.403559644001;6098.298695649968;6159.983602705593;6229.719662615194;928.4522131942051;980.6562316064611;985.8866230383454;1005.1406047829001;1025.1849779425374;4049.6760259179196;4140.91718165641;4170.645495132887;4194.454720886326;4206.768627725443;10233.334833235816;10493.26547141708;10669.19981001428;10692.62575809843;10897.564097436172;10546.156307495383;10673.306235094695;10764.46177691128;10771.407572583426;10771.515281812337;8081.151479094728;8360.581970901309;8369.62336694838;8373.41386102979;8391.286740627114;5989.131575921554;6083.087536869328;6090.878182436415;6234.438900498977;6354.015127655221;9451;9861.457619830979;10112.949435253071;10115.190784737057;10119.089281964658;9864.506774661382;10190.796184076426;10226.386416815016;10351.896481035094;10353.327033742782;6428.19647544057;6949.51353405277;7599.4680372373405;7662;7787.5716835574585;7111.359977602041;7834.960825195838;8398;8413.368997325348;8486.19381158789;7404.96297518509;7587.355074818482;7590.0891892973195;7670.386369090348;7763.68945242184;7416.851662966603;7426.962865185639;7457.813554661177;7494.775156745306;7498.250174982458;7076.150861896605;7154.642267886481;7157.964210178916;7159.463040271896;7165.7850264492145;4987.451380348231;5009.4489606144025;5027.874303142451;5153.123968925217;5208.713520756395;5084;5084.237364395371;5136.563392111712;5175.120229560908;5289.4710528948335 10:45:47 INFO - PROCESS | 1661 | |4;prototype.html;299.94301082794266;301.4002135749858;311.30889425475453;315.47001038255723;316.45253711079846;4369.978150109253;5461.535769459596;5529.364123125832;5561.833145005646;5583.3579138399045;5127.6413948863465;5139.871503212422;5301.973490132544;5303.363596368432;5499.940055149263;3672.889813305605;3745.3071181831447;3763.322601931651;3926.096997690533;4108.760725404647;3712.0905378182383;3985.820638071279;4205.222033923718;4228.492580890297;4252;3869.0520822398553;3942.56631770505;4090.9590904090874;4100.384942258655;4151.273527132755;3728.1425272187275;3855.20968201518;4110.650594699454;4111.342185250345;4147.025449019478;3389.2204792897523;3455.87904423344;3492.511048453219;3495.405781017233;3509.2279698466295;5256.290400795881;5308.5487733542695;5313.707746073965;5317.202419637047;5344.156266088194;4727.692699974525;4748.5251474852585;4764.714117152982;4780.235162374003;4801.927971080437;5322;5324.733763311819;5362.678239305654;5517;5786.334571524244;3283.5074738789135;3874.740709269499;3900.0639846436934;3902.790135058113;3937.5471820740695;5517.889642207174;5938.524918006549;6160.275122965532;6172.179207133861;6172.938270617281;3352.580927384077;3914.4146620618694;3953.150072734376;3967.7464479127416;3973.4039894016128;3551.5560554930635;3926.155876486569;4006.7595944242858;4027.275090483741;4032.9596704032583;3821.38857782759;4454.665900057508;4525.343825145335;4741.502142275081;4745.928811067837;5356.839294821161;5866.919867069822;5872.7357268923;5884.705764711747;5907.868829520361;1286.75551645189;1338.7188690374935;1346.7171893902193;1360.6258278973164;1365.235468137846;853.5390888920037;874.1870060843477;896.0949441064441;924.9907500924904;925.5511077127587;5199.402068762065;5482.314710661168;5482.5888058394985;5485.780568777204;5508.394076651565;5057.772400241998;5259.421463638997;5487.259220005328;5625.774969001276;5983.431574000463;5220.851412689202;5240.292560504283;5370.62405631602;5382.892342153214;5432.066683990601;2826;3077.8922737704074;3092;3105.2392163919985;3165.2561648012697;5627.71861406928;5727.885442291131;5977.372375900555;6083.6045657032155;6160.537959652955;5971.7611295547695;6014.7594096236535;6056.485198758067;6106.938930610726;6108.526046950992;5337.599680024014;5738.942610573924;5798.652080875161;6045.72794224261;6088;5225.216217567243;5620.110503861666;5856.326522449803;5889.293284805851;5916.378780228186;4910.779014944264;5543.556515478671;5709.628874123168;5787.710614469175;5858.970705146447;5522.502974732293;5583.190437386637;5714.599978001666;6048;6081.5742897996715;3926.9214615708097;3926.9607303926596;3954.2517950024458;3974.1852920150723;3982.940255896106;5252.711100889488;5471.331243970627;5935.940640593539;5952.613080149777;5952.613080149949;4807.254875494304;5333.916927377284;5698.6580805151825;5699.230603868425;5758.379937128513;5622.465865742748;5699.373068962328;5721.255017219788;5725.541956643542;5726.398728133652;4827.73447460379;5281.366236051545;5518.42056584069;5583.944160558505;5640.32316122068;4878.487758785276;5583.078791999437;5631.802886898939;5729.885402292014;5732.627379220337;4755.524447555217;5277.8416647500635;5516.586256030733;5567.164925261243;5684.658920464785 10:45:47 INFO - PROCESS | 1661 | |5;yui.html;717.3866344275644;743.5352904434728;744.2148533297371;759.8404335089631;766.7967988483053;14446.27768611569;16210.594735131628;16234.188290585482;16242.593935151626;16245.593860153502;14553.272336383165;14666.413343466249;14702.705945881076;14710.411583536672;14717.116972981628;708.2209569473589;725;876.8597024476085;879.8680197970308;908.2007833106857;888.8266787976347;898.955052247385;901.7159594727673;902.309733054214;902.5442151713376;952.2905435450599;952.3809523809545;952.4666186935303;953.5518306396017;954.5513608603945;736.6390468670339;762.4891322813728;762.6034462079743;766.3600893254139;948.2603569216022;684.2985939412092;702.1784512120831;704.5526090932244;707.2962402409584;710.6127160697432;15259.847401525954;15301.311440985071;15320.923395383063;15331.003484773566;15355.385784568603;13635.863641363558;13722.794158087636;13801.378937947817;13825.446982120704;14085.366158522891;12728.218049473082;12743.681407964783;12759.489620415265;12940.288284144368;13246.40391182399;4505.887352816206;4540.682152249344;4585.335126406652;4706.7646617669325;4730.195866702668;102883.48558257235;103688.96311036868;104824.47587762089;104947;104965.47517262441;11016.889831101586;11060.502277397536;11097.611583594535;11100.777984440267;11390.772184556263;21822.563548728936;21882.343529694135;21952.670709939364;21959.451013724465;22004.33986980393;7685.269899359552;7765.3011228988535;7792.493487923267;7913.723019694398;7933.206679332021;4387.166438376698;4467.865964021084;4472.485664148665;4474.6420286377015;4583.100250986374;3276.8689252430113;3386.779859309137;3390.5931288245074;3392.830358482066;3393.2025973896116;635.4852569418786;640.2061443809642;648.970796314167;650.5673726971511;653.2389765922742;594.9167116603679;631.0280373831775;636.3636363636364;652.5823472977298;653.5947712418331;8658.50014938746;9024.142706442877;9038.593263303035;9049.592768325441;9103.453792772454;612.8896798576299;622.7135517661835;628.4438272129198;636.8376064103624;640.7116797441076;4190.664746820247;4322.15717935006;4331.350297455344;4383.919969249639;4538.818447262072;135709;137109;141913.5808641921;148654;148906;97312;127150;127418;128946;132177;83666;106768;107523.38714919283;110590.34114488288;113156.43421783003;8507.914920850711;8517.97784265892;8525.488470691778;8529.232369086812;8863.645454182019;62022.37977620166;62426;63346;63515.6824215876;65128.320015930345;90318.0968190336;93849.5307523458;94291;95257.57113643162;96009.51995240258;11573.884261157618;11651;11719.531218751496;11804.645860624196;12320.630381088582;12082.516699331929;12207.87792122103;12316.568920087753;12394.81407778902;12913.612591622266;11835.940820295844;12044.337561434206;12114.575989840665;12324.383780810744;12340.533722849139;10696;11720.355380454157;11982.820257696316;12009.459574319351;12073.818892716792;11691.824622630838;11717.238379505305;11724.179307448398;11794.29234245948;12127.939360303495;9540.90459095428;9771.75926619449;10006.249531285037;10194.184465242912;10223.897761022592;9782.510874456391;10085.798284034425;10107.949460252652;10216.795664086529;10263.692089237335 10:45:47 INFO - PROCESS | 1661 | __end_tp_report 10:45:47 INFO - PROCESS | 1661 | __start_cc_report 10:45:47 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1281 10:45:47 INFO - PROCESS | 1661 | __end_cc_report 10:45:47 INFO - PROCESS | 1661 | __startTimestamp1468259147277__endTimestamp 10:45:47 INFO - PROCESS | 1661 | 10:45:47 INFO - PROCESS | 1661 | ------- Summary: start ------- 10:45:47 INFO - PROCESS | 1661 | Number of tests: 6 10:45:47 INFO - PROCESS | 1661 | 10:45:47 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5755.90 Median:6030.61 stddev:641.23 (10.6%) stddev-sans-first:613.61 10:45:47 INFO - PROCESS | 1661 | Values: 3301.7 3303.7 3328.0 3371.1 3383.1 5597.7 5668.7 5690.6 5805.1 5823.7 5702.3 5705.9 5812.3 5836.4 5882.1 5156.0 5162.1 5220.0 5220.2 5353.9 5309.2 5339.4 5342.2 5353.9 5397.7 5134.8 5201.8 5217.1 5259.2 5272.2 5193.2 5208.7 5250.8 5268.3 5283.0 5168.5 5171.4 5184.8 5214.2 5224.1 5694.9 5761.1 5776.5 5798.0 5800.7 6123.8 6162.8 6227.9 6292.8 6303.1 6247.7 6257.3 6257.9 6265.6 6273.7 5854.9 5858.2 5902.3 5904.4 5920.0 6143.3 6216.3 6252.0 6263.9 6284.5 6192.2 6198.0 6202.4 6202.9 6205.7 6288.2 6291.6 6296.4 6298.8 6304.0 6103.1 6116.9 6137.4 6142.2 6196.3 6161.6 6186.3 6231.6 6304.2 6318.4 4933.6 4943.7 4951.3 4960.4 4984.9 4340.7 4454.7 4462.6 4464.3 4499.9 5571.2 5721.7 5730.4 5738.0 5741.5 6186.3 6254.8 6274.6 6288.5 6301.5 5207.8 5485.8 5763.3 5764.2 5782.1 5308.5 5380.1 5422.7 5439.0 5441.4 5679.2 6233.5 6353.3 6363.4 6376.9 6252.9 6293.7 6296.1 6308.4 6318.8 5407.4 6136.5 6227.6 6236.8 6346.9 6222.9 6244.7 6257.5 6257.6 6265.9 6073.4 6280.2 6292.2 6297.4 6298.0 5971.4 6055.2 6115.9 6132.9 6161.1 5180.0 6137.9 6184.2 6188.4 6191.2 6040.5 6040.9 6057.6 6067.7 6078.0 5948.5 6048.5 6087.1 6090.9 6132.4 6012.5 6021.0 6030.6 6038.8 6048.5 10:45:47 INFO - PROCESS | 1661 | 10:45:47 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:72219.51 Median:38110.81 stddev:159161.67 (417.6%) stddev-sans-first:159517.00 10:45:47 INFO - PROCESS | 1661 | Values: 473.9 508.0 516.0 519.1 534.9 29460.0 30512.8 31505.7 31553.1 31563.8 31985.7 41658.0 42597.6 42850.4 45145.5 9555.6 9702.4 9705.9 9706.6 9718.3 8628.3 9670.0 9776.6 9776.7 9795.3 8624.7 8910.0 8927.7 8981.6 11749.8 11815.9 12094.3 12112.3 12113.0 12133.0 11911.4 11983.7 11990.5 12024.0 12024.6 31993.8 40105.8 40198.0 40949.0 41181.0 3435.9 3679.9 3879.9 4133.3 4409.0 31438.8 31660.7 32293.4 33481.3 33598.8 3214.3 3324.1 3350.2 3420.4 3425.0 514967.0 516649.0 519094.0 519485.0 519942.0 53832.2 62563.7 63667.0 63681.7 63966.7 67665.0 67678.7 67896.0 67960.3 68133.3 16626.8 16899.3 16918.7 16933.0 16937.0 10964.9 12989.4 12999.4 13003.5 13019.5 16551.6 19701.0 19710.2 19742.8 19774.8 6778.5 8049.6 8167.1 8354.7 8479.4 884.7 981.7 1024.8 1097.2 1138.2 30264.7 30366.7 30747.7 30801.0 30936.5 1057.2 1061.7 1069.6 1085.7 1121.4 13213.7 15916.8 15917.4 16380.6 16390.3 617648.0 618608.0 619176.0 623727.9 628254.0 52035.5 58990.4 60206.0 60765.0 60786.0 1412.5 1431.4 1443.1 1449.6 1449.6 26698.3 31096.2 31712.0 31757.4 32411.8 52303.5 52437.7 52564.7 52735.7 52741.0 565651.0 575117.0 575150.0 624454.0 815934.0 25686.5 26627.9 26782.3 26783.5 26800.1 46419.8 47328.0 47689.3 48102.8 48309.5 38271.6 38746.0 39183.8 39808.2 39847.2 38285.0 38505.6 38800.8 39584.8 40515.8 36774.3 37605.0 38063.6 38158.0 38203.4 26511.6 34172.7 34256.0 34358.0 34386.5 24787.5 24942.0 25021.2 25129.6 25686.2 10:45:47 INFO - PROCESS | 1661 | 10:45:47 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:33167.33 Median:14146.62 stddev:93073.45 (657.9%) stddev-sans-first:93301.86 10:45:47 INFO - PROCESS | 1661 | Values: 313.6 349.7 367.4 375.3 400.4 15641.6 17609.9 20523.6 21263.4 21272.9 21128.0 21601.4 21950.0 21961.1 22117.3 5303.7 5609.6 6043.7 6103.0 6108.6 5940.6 6000.7 6039.3 6049.9 6061.8 5754.2 5980.4 5997.0 6006.6 6010.5 5913.4 5930.6 5967.0 5967.7 5967.8 5104.2 5408.9 5412.9 5426.5 5427.5 12982.0 13306.9 13447.7 18044.3 25703.6 2299.4 2462.8 2589.6 2638.5 2958.9 9928.5 9975.9 10063.0 10302.3 10330.9 1757.3 1800.8 1833.1 1839.3 1852.7 81692.0 113513.0 114872.4 117374.8 122033.4 9401.6 10911.4 11040.9 11272.3 11290.6 14198.2 14241.9 14264.8 14320.6 14333.5 8495.6 8642.4 8679.0 8770.2 8824.5 8060.4 8556.2 8557.4 8667.1 8672.6 4391.2 4441.3 4471.8 4500.4 4563.7 1756.2 1872.8 1897.3 1898.3 1908.0 774.9 957.8 1011.5 1018.8 1076.4 19187.0 20336.6 20405.0 21535.4 21582.5 966.6 982.6 1010.3 1032.7 1045.2 9206.5 10760.2 11039.9 11235.8 11294.6 512875.0 558446.0 568121.0 571874.0 575115.0 74586.0 95936.0 115068.4 124310.8 147887.3 914.8 938.5 949.4 951.0 973.8 18823.6 18935.2 19060.5 19148.6 19346.8 51012.5 52640.7 53409.0 60685.0 61743.0 74842.0 77327.2 79961.2 83452.6 86107.0 7747.2 7997.9 8038.0 8054.9 8227.8 12913.7 12933.9 13006.7 13249.5 13411.3 11273.0 11416.7 11548.0 11692.9 11698.8 10269.6 11190.9 11328.2 11381.1 11609.1 11098.9 11422.3 11868.1 11906.6 11933.7 8314.5 8366.8 8381.9 8386.1 8534.1 11083.1 11576.5 11743.5 11783.6 11910.9 10:45:47 INFO - PROCESS | 1661 | 10:45:47 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5700.61 Median:4998.45 stddev:3173.75 (63.5%) stddev-sans-first:3167.47 10:45:47 INFO - PROCESS | 1661 | Values: 1569.9 1600.4 1803.0 1920.3 1935.3 6950.6 8564.4 9035.5 9050.5 9061.9 7794.3 8030.6 8036.8 8184.0 8758.6 2144.2 2285.4 2369.2 2382.8 2391.2 2104.5 2340.9 2370.5 2388.7 2389.3 2087.1 2366.7 2367.1 2370.9 2373.0 2272.0 2345.7 2348.2 2352.1 2352.6 2328.5 2344.5 2349.6 2351.3 2361.2 7671.0 7757.5 8891.0 8925.0 8925.8 5197.3 5359.4 5367.2 5367.5 5367.6 3439.8 3519.4 3581.4 3591.9 3595.7 1048.5 1063.0 1070.4 1085.7 1088.9 11714.9 11764.6 11765.7 11771.2 11813.9 4461.4 4655.5 4730.2 4741.6 4750.3 8523.5 9781.9 9895.8 9942.4 9956.2 5341.1 5341.9 5345.5 5352.0 5352.2 3952.4 4420.8 4907.1 4921.3 4928.2 3527.1 3698.7 3768.1 3801.2 3806.5 1134.7 1138.1 1148.4 1148.6 1153.7 953.7 958.3 972.3 988.2 1004.5 5949.8 5964.4 6098.3 6160.0 6229.7 928.5 980.7 985.9 1005.1 1025.2 4049.7 4140.9 4170.6 4194.5 4206.8 10233.3 10493.3 10669.2 10692.6 10897.6 10546.2 10673.3 10764.5 10771.4 10771.5 8081.2 8360.6 8369.6 8373.4 8391.3 5989.1 6083.1 6090.9 6234.4 6354.0 9451.0 9861.5 10112.9 10115.2 10119.1 9864.5 10190.8 10226.4 10351.9 10353.3 6428.2 6949.5 7599.5 7662.0 7787.6 7111.4 7835.0 8398.0 8413.4 8486.2 7405.0 7587.4 7590.1 7670.4 7763.7 7416.9 7427.0 7457.8 7494.8 7498.3 7076.2 7154.6 7158.0 7159.5 7165.8 4987.5 5009.4 5027.9 5153.1 5208.7 5084.0 5084.2 5136.6 5175.1 5289.5 10:45:47 INFO - PROCESS | 1661 | 10:45:47 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4638.86 Median:5311.13 stddev:1432.35 (27.0%) stddev-sans-first:1398.86 10:45:47 INFO - PROCESS | 1661 | Values: 299.9 301.4 311.3 315.5 316.5 4370.0 5461.5 5529.4 5561.8 5583.4 5127.6 5139.9 5302.0 5303.4 5499.9 3672.9 3745.3 3763.3 3926.1 4108.8 3712.1 3985.8 4205.2 4228.5 4252.0 3869.1 3942.6 4091.0 4100.4 4151.3 3728.1 3855.2 4110.7 4111.3 4147.0 3389.2 3455.9 3492.5 3495.4 3509.2 5256.3 5308.5 5313.7 5317.2 5344.2 4727.7 4748.5 4764.7 4780.2 4801.9 5322.0 5324.7 5362.7 5517.0 5786.3 3283.5 3874.7 3900.1 3902.8 3937.5 5517.9 5938.5 6160.3 6172.2 6172.9 3352.6 3914.4 3953.2 3967.7 3973.4 3551.6 3926.2 4006.8 4027.3 4033.0 3821.4 4454.7 4525.3 4741.5 4745.9 5356.8 5866.9 5872.7 5884.7 5907.9 1286.8 1338.7 1346.7 1360.6 1365.2 853.5 874.2 896.1 925.0 925.6 5199.4 5482.3 5482.6 5485.8 5508.4 5057.8 5259.4 5487.3 5625.8 5983.4 5220.9 5240.3 5370.6 5382.9 5432.1 2826.0 3077.9 3092.0 3105.2 3165.3 5627.7 5727.9 5977.4 6083.6 6160.5 5971.8 6014.8 6056.5 6106.9 6108.5 5337.6 5738.9 5798.7 6045.7 6088.0 5225.2 5620.1 5856.3 5889.3 5916.4 4910.8 5543.6 5709.6 5787.7 5859.0 5522.5 5583.2 5714.6 6048.0 6081.6 3926.9 3927.0 3954.3 3974.2 3982.9 5252.7 5471.3 5935.9 5952.6 5952.6 4807.3 5333.9 5698.7 5699.2 5758.4 5622.5 5699.4 5721.3 5725.5 5726.4 4827.7 5281.4 5518.4 5583.9 5640.3 4878.5 5583.1 5631.8 5729.9 5732.6 4755.5 5277.8 5516.6 5567.2 5684.7 10:45:47 INFO - PROCESS | 1661 | 10:45:47 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:24038.92 Median:4256.41 stddev:38220.28 (897.9%) stddev-sans-first:38287.38 10:45:47 INFO - PROCESS | 1661 | Values: 717.4 743.5 744.2 759.8 766.8 14446.3 16210.6 16234.2 16242.6 16245.6 14553.3 14666.4 14702.7 14710.4 14717.1 708.2 725.0 876.9 879.9 908.2 888.8 899.0 901.7 902.3 902.5 952.3 952.4 952.5 953.6 954.6 736.6 762.5 762.6 766.4 948.3 684.3 702.2 704.6 707.3 710.6 15259.8 15301.3 15320.9 15331.0 15355.4 13635.9 13722.8 13801.4 13825.4 14085.4 12728.2 12743.7 12759.5 12940.3 13246.4 4505.9 4540.7 4585.3 4706.8 4730.2 102883.5 103689.0 104824.5 104947.0 104965.5 11016.9 11060.5 11097.6 11100.8 11390.8 21822.6 21882.3 21952.7 21959.5 22004.3 7685.3 7765.3 7792.5 7913.7 7933.2 4387.2 4467.9 4472.5 4474.6 4583.1 3276.9 3386.8 3390.6 3392.8 3393.2 635.5 640.2 649.0 650.6 653.2 594.9 631.0 636.4 652.6 653.6 8658.5 9024.1 9038.6 9049.6 9103.5 612.9 622.7 628.4 636.8 640.7 4190.7 4322.2 4331.4 4383.9 4538.8 135709.0 137109.0 141913.6 148654.0 148906.0 97312.0 127150.0 127418.0 128946.0 132177.0 83666.0 106768.0 107523.4 110590.3 113156.4 8507.9 8518.0 8525.5 8529.2 8863.6 62022.4 62426.0 63346.0 63515.7 65128.3 90318.1 93849.5 94291.0 95257.6 96009.5 11573.9 11651.0 11719.5 11804.6 12320.6 12082.5 12207.9 12316.6 12394.8 12913.6 11835.9 12044.3 12114.6 12324.4 12340.5 10696.0 11720.4 11982.8 12009.5 12073.8 11691.8 11717.2 11724.2 11794.3 12127.9 9540.9 9771.8 10006.2 10194.2 10223.9 9782.5 10085.8 10107.9 10216.8 10263.7 10:45:47 INFO - PROCESS | 1661 | -------- Summary: end -------- 10:45:47 INFO - PROCESS | 1661 | 10:45:47 INFO - TEST-INFO | 1661: exit 0 10:45:47 INFO - TEST-OK | dromaeo_css | took 1116448ms 10:45:47 INFO - TEST-START | kraken 10:45:47 INFO - operating with platform_type : mac_ 10:45:47 INFO - Initialising browser for kraken test... 10:45:47 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmp538cX2/profile http://localhost:49276/getInfo.html) 10:45:50 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 10:45:50 INFO - PROCESS | 1668 | colorDepth:24 10:45:50 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 10:45:50 INFO - PROCESS | 1668 | __metrics 10:45:50 INFO - PROCESS | 1668 | 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:45:50 INFO - PROCESS | 1668 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:45:51 INFO - TEST-INFO | 1668: exit 0 10:45:51 INFO - Browser initialized. 10:45:51 INFO - Running cycle 1/1 for kraken test... 10:45:51 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/T/tmp538cX2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:46:02 INFO - PROCESS | 1670 | RSS: Main: 243568640 10:46:02 INFO - PROCESS | 1670 | 10:46:05 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 10:46:06 INFO - PROCESS | 1670 | RSS: Main: 294334464 10:46:06 INFO - PROCESS | 1670 | 10:46:09 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 10:46:09 INFO - PROCESS | 1670 | RSS: Main: 304365568 10:46:09 INFO - PROCESS | 1670 | 10:46:13 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 10:46:14 INFO - PROCESS | 1670 | RSS: Main: 389316608 10:46:14 INFO - PROCESS | 1670 | 10:46:16 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 10:46:16 INFO - PROCESS | 1670 | RSS: Main: 290385920 10:46:16 INFO - PROCESS | 1670 | 10:46:18 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:46:19 INFO - PROCESS | 1670 | RSS: Main: 294297600 10:46:19 INFO - PROCESS | 1670 | 10:46:22 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 10:46:22 INFO - PROCESS | 1670 | RSS: Main: 330833920 10:46:22 INFO - PROCESS | 1670 | 10:46:25 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 10:46:26 INFO - PROCESS | 1670 | RSS: Main: 332632064 10:46:26 INFO - PROCESS | 1670 | 10:46:28 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 10:46:29 INFO - PROCESS | 1670 | RSS: Main: 330559488 10:46:29 INFO - PROCESS | 1670 | 10:46:31 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:46:31 INFO - PROCESS | 1670 | RSS: Main: 311799808 10:46:31 INFO - PROCESS | 1670 | 10:46:33 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:46:34 INFO - PROCESS | 1670 | RSS: Main: 318054400 10:46:34 INFO - PROCESS | 1670 | 10:46:36 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:46:37 INFO - PROCESS | 1670 | RSS: Main: 316416000 10:46:37 INFO - PROCESS | 1670 | 10:46:40 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:46:40 INFO - PROCESS | 1670 | RSS: Main: 324988928 10:46:40 INFO - PROCESS | 1670 | 10:46:43 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:46:44 INFO - PROCESS | 1670 | RSS: Main: 327213056 10:46:44 INFO - PROCESS | 1670 | 10:46:45 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 10:46:46 INFO - PROCESS | 1670 | RSS: Main: 370933760 10:46:46 INFO - PROCESS | 1670 | 10:46:46 INFO - PROCESS | 1670 | __start_tp_report 10:46:46 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 10:46:46 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 10:46:46 INFO - PROCESS | 1670 | |i|pagename|runs| 10:46:46 INFO - PROCESS | 1670 | |0;ai-astar;100;114;113;113;114;114;113;161;113;114 10:46:46 INFO - PROCESS | 1670 | |1;audio-beat-detection;143;108;131;194;134;107;131;105;138;106 10:46:46 INFO - PROCESS | 1670 | |2;audio-dft;184;183;187;193;183;182;180;181;183;183 10:46:46 INFO - PROCESS | 1670 | |3;audio-fft;79;77;58;107;57;57;60;60;125;67 10:46:46 INFO - PROCESS | 1670 | |4;audio-oscillator;82;89;91;71;71;67;95;70;67;99 10:46:46 INFO - PROCESS | 1670 | |5;imaging-gaussian-blur;88;87;91;89;91;86;90;86;89;85 10:46:46 INFO - PROCESS | 1670 | |6;imaging-darkroom;94;94;94;94;94;94;94;94;93;94 10:46:46 INFO - PROCESS | 1670 | |7;imaging-desaturate;86;83;83;84;84;86;85;85;85;92 10:46:46 INFO - PROCESS | 1670 | |8;json-parse-financial;54;54;54;55;54;55;54;56;53;54 10:46:46 INFO - PROCESS | 1670 | |9;json-stringify-tinderbox;66;71;68;82;71;72;70;69;70;73 10:46:46 INFO - PROCESS | 1670 | |10;stanford-crypto-aes;85;85;84;95;102;76;84;87;84;85 10:46:46 INFO - PROCESS | 1670 | |11;stanford-crypto-ccm;135;144;145;149;137;126;138;141;226;136 10:46:46 INFO - PROCESS | 1670 | |12;stanford-crypto-pbkdf2;165;162;161;162;161;161;162;165;159;161 10:46:46 INFO - PROCESS | 1670 | |13;stanford-crypto-sha256-iterative;65;67;69;66;65;64;65;66;66;64 10:46:46 INFO - PROCESS | 1670 | __end_tp_report 10:46:46 INFO - PROCESS | 1670 | __start_cc_report 10:46:46 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,1989 10:46:46 INFO - PROCESS | 1670 | __end_cc_report 10:46:46 INFO - PROCESS | 1670 | __startTimestamp1468259206045__endTimestamp 10:46:46 INFO - PROCESS | 1670 | 10:46:46 INFO - PROCESS | 1670 | ------- Summary: start ------- 10:46:46 INFO - PROCESS | 1670 | Number of tests: 14 10:46:46 INFO - PROCESS | 1670 | 10:46:46 INFO - PROCESS | 1670 | [#0] ai-astar Cycles:10 Average:116.90 Median:114.00 stddev:16.07 (14.1%) stddev-sans-first:15.84 10:46:46 INFO - PROCESS | 1670 | Values: 100.0 114.0 113.0 113.0 114.0 114.0 113.0 161.0 113.0 114.0 10:46:46 INFO - PROCESS | 1670 | 10:46:46 INFO - PROCESS | 1670 | [#1] audio-beat-detection Cycles:10 Average:129.70 Median:132.50 stddev:27.00 (20.4%) stddev-sans-first:28.20 10:46:46 INFO - PROCESS | 1670 | Values: 143.0 108.0 131.0 194.0 134.0 107.0 131.0 105.0 138.0 106.0 10:46:46 INFO - PROCESS | 1670 | 10:46:46 INFO - PROCESS | 1670 | [#2] audio-dft Cycles:10 Average:183.90 Median:183.00 stddev:3.70 (2.0%) stddev-sans-first:3.92 10:46:46 INFO - PROCESS | 1670 | Values: 184.0 183.0 187.0 193.0 183.0 182.0 180.0 181.0 183.0 183.0 10:46:46 INFO - PROCESS | 1670 | 10:46:46 INFO - PROCESS | 1670 | [#3] audio-fft Cycles:10 Average:74.70 Median:60.00 stddev:23.56 (39.3%) stddev-sans-first:24.93 10:46:46 INFO - PROCESS | 1670 | Values: 79.0 77.0 58.0 107.0 57.0 57.0 60.0 60.0 125.0 67.0 10:46:46 INFO - PROCESS | 1670 | 10:46:46 INFO - PROCESS | 1670 | [#4] audio-oscillator Cycles:10 Average:80.20 Median:85.50 stddev:12.43 (14.5%) stddev-sans-first:13.17 10:46:46 INFO - PROCESS | 1670 | Values: 82.0 89.0 91.0 71.0 71.0 67.0 95.0 70.0 67.0 99.0 10:46:46 INFO - PROCESS | 1670 | 10:46:46 INFO - PROCESS | 1670 | [#5] imaging-gaussian-blur Cycles:10 Average:88.20 Median:89.00 stddev:2.15 (2.4%) stddev-sans-first:2.28 10:46:46 INFO - PROCESS | 1670 | Values: 88.0 87.0 91.0 89.0 91.0 86.0 90.0 86.0 89.0 85.0 10:46:46 INFO - PROCESS | 1670 | 10:46:46 INFO - PROCESS | 1670 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 10:46:46 INFO - PROCESS | 1670 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 94.0 10:46:46 INFO - PROCESS | 1670 | 10:46:46 INFO - PROCESS | 1670 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.00 stddev:2.58 (3.0%) stddev-sans-first:2.73 10:46:46 INFO - PROCESS | 1670 | Values: 86.0 83.0 83.0 84.0 84.0 86.0 85.0 85.0 85.0 92.0 10:46:46 INFO - PROCESS | 1670 | 10:46:46 INFO - PROCESS | 1670 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.00 stddev:0.82 (1.5%) stddev-sans-first:0.87 10:46:46 INFO - PROCESS | 1670 | Values: 54.0 54.0 54.0 55.0 54.0 55.0 54.0 56.0 53.0 54.0 10:46:46 INFO - PROCESS | 1670 | 10:46:46 INFO - PROCESS | 1670 | [#9] json-stringify-tinderbox Cycles:10 Average:71.20 Median:71.00 stddev:4.29 (6.0%) stddev-sans-first:4.12 10:46:46 INFO - PROCESS | 1670 | Values: 66.0 71.0 68.0 82.0 71.0 72.0 70.0 69.0 70.0 73.0 10:46:46 INFO - PROCESS | 1670 | 10:46:46 INFO - PROCESS | 1670 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:85.00 stddev:7.06 (8.3%) stddev-sans-first:7.46 10:46:46 INFO - PROCESS | 1670 | Values: 85.0 85.0 84.0 95.0 102.0 76.0 84.0 87.0 84.0 85.0 10:46:46 INFO - PROCESS | 1670 | 10:46:46 INFO - PROCESS | 1670 | [#11] stanford-crypto-ccm Cycles:10 Average:147.70 Median:142.50 stddev:28.24 (19.8%) stddev-sans-first:29.57 10:46:46 INFO - PROCESS | 1670 | Values: 135.0 144.0 145.0 149.0 137.0 126.0 138.0 141.0 226.0 136.0 10:46:46 INFO - PROCESS | 1670 | 10:46:46 INFO - PROCESS | 1670 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.90 Median:162.00 stddev:1.85 (1.1%) stddev-sans-first:1.59 10:46:46 INFO - PROCESS | 1670 | Values: 165.0 162.0 161.0 162.0 161.0 161.0 162.0 165.0 159.0 161.0 10:46:46 INFO - PROCESS | 1670 | 10:46:46 INFO - PROCESS | 1670 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:1.49 (2.3%) stddev-sans-first:1.56 10:46:46 INFO - PROCESS | 1670 | Values: 65.0 67.0 69.0 66.0 65.0 64.0 65.0 66.0 66.0 64.0 10:46:46 INFO - PROCESS | 1670 | -------- Summary: end -------- 10:46:46 INFO - PROCESS | 1670 | 10:46:46 INFO - TEST-INFO | 1670: exit 0 10:46:46 INFO - TEST-OK | kraken | took 59036ms 10:46:46 INFO - SUITE-END | took 1175s 10:46:47 INFO - Completed test suite (00:19:35) 10:46:47 INFO - javascript benchmark 10:46:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3301.669833016698, 3303.735701143907, 3328.0015995201443, 3371.0898057524455, 3383.0865666270106, 5597.748101335439, 5668.659880407178, 5690.5732070094755, 5805.100209467529, 5823.708814559276, 5702.258706368177, 5705.942940570603, 5812.3025236971625, 5836.38717934616, 5882.14708867214, 5155.999999999981, 5162.096633089213, 5219.973900130494, 5220.216967454893, 5353.9196912046355, 5309.203619457093, 5339.41266460689, 5342.198670199462, 5353.946460535384, 5397.7031263280505, 5134.845954621367, 5201.843944681666, 5217.113090774539, 5259.237410575483, 5272.235525848769, 5193.195054766517, 5208.687478751249, 5250.842474725726, 5268.262443257947, 5282.973585132088, 5168.457311982225, 5171.405288391846, 5184.792608295701, 5214.191800270964, 5224.111900976843, 5694.857628559319, 5761.078227483625, 5776.451237132508, 5798.043322851724, 5800.680962547058, 6123.846903827395, 6162.815115546541, 6227.875442491125, 6292.811215663537, 6303.149074874879, 6247.687615619201, 6257.311695713468, 6257.906131407987, 6265.592736472115, 6273.686315684243, 5854.882902341929, 5858.209141765807, 5902.291724993029, 5904.439078287556, 5920.023196172583, 6143.266246520345, 6216.316205217422, 6252.030935205051, 6263.874722505524, 6284.497240220771, 6192.195014648067, 6198.039303907902, 6202.4417802398, 6202.906956395657, 6205.720742566596, 6288.182536270256, 6291.591046581958, 6296.370362963667, 6298.811035668938, 6303.959846625347, 6103.1455596216565, 6116.853194528077, 6137.376985335611, 6142.170806941095, 6196.287426945869, 6161.599496032743, 6186.319504854462, 6231.563790534619, 6304.211973503312, 6318.399752023551, 4933.605311575066, 4943.703377797344, 4951.257311403247, 4960.429550601655, 4984.850454486371, 4340.674449416307, 4454.710443821141, 4462.620677242406, 4464.30803225494, 4499.865004049884, 5571.220029195835, 5721.656700597978, 5730.36965933747, 5737.971310143423, 5741.483266505951, 6186.257649082139, 6254.781082662085, 6274.560780745304, 6288.52836037299, 6301.495880329563, 5207.765650545734, 5485.835424937259, 5763.279590051244, 5764.221830052889, 5782.132680097935, 5308.46915308466, 5380.112281473513, 5422.674639521641, 5439.048166570834, 5441.374241962146, 5679.195721413481, 6233.470155036873, 6353.3011368748475, 6363.3636636336, 6376.904346434714, 6252.937470625236, 6293.685315734104, 6296.118543403835, 6308.369163083655, 6318.778842740482, 5407.378151512627, 6136.457808108256, 6227.564070515021, 6236.781712640036, 6346.873062538631, 6222.875542489215, 6244.656543889948, 6257.499400047895, 6257.5932564383165, 6265.874682506415, 6073.392660733891, 6280.246370435577, 6292.2134733158355, 6297.401746834226, 6298.0, 5971.373005834324, 6055.182550355822, 6115.877682446415, 6132.8773424532155, 6161.137440758387, 5180.0157969985585, 6137.91625765497, 6184.196054512884, 6188.44304012641, 6191.226096737908, 6040.456358927718, 6040.939590604038, 6057.569730008609, 6067.67447169642, 6078.0, 5948.524118070631, 6048.546359023003, 6087.05650624154, 6090.9390906090375, 6132.38676132401, 6012.549058820693, 6021.006533921854, 6030.6080104793045, 6038.788642397495, 6048.455638992512], "value": 5714.578976354635, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.8767920340711, 508.0499465998581, 515.9896802063959, 519.0941806547573, 534.893021395721, 29460.0, 30512.779488820448, 31505.68494315056, 31553.05340839773, 31563.84218078912, 31985.680143198562, 41658.0, 42597.574024259746, 42850.35724464135, 45145.548544514546, 9555.617775288998, 9702.369345992487, 9705.90294097057, 9706.61173553057, 9718.271129665269, 8628.309735221168, 9670.032996700344, 9776.608935642602, 9776.680148180012, 9795.314327997043, 8624.741257762245, 8910.0, 8927.687530936419, 8981.595828187714, 11749.7650046999, 11815.881841181606, 12094.334811585359, 12112.273263604167, 12113.0, 12133.0, 11911.404429778477, 11983.700407489883, 11990.520379184822, 12024.0, 12024.639260822189, 31993.84003079993, 40105.79947100246, 40197.99505012369, 40948.97627559305, 41181.0, 3435.914102147441, 3679.8528058877614, 3879.9224015519812, 4133.2973394522705, 4408.955910440886, 31438.842805786055, 31660.683393166233, 32293.354132917444, 33481.33037339264, 33598.83200583982, 3214.260720034405, 3324.1191084362663, 3350.163586084988, 3420.4185288500767, 3424.989628059728, 514967.0, 516649.0, 519094.0, 519484.9999999962, 519942.0, 53832.19251711227, 62563.68718156471, 63667.0, 63681.726365473354, 63966.72066558736, 67665.0, 67678.66160669264, 67896.0, 67960.3203967954, 68133.29666758371, 16626.83373166277, 16899.32402703878, 16918.661626767644, 16932.984020958782, 16936.98378097318, 10964.945175274232, 12989.4154763034, 12999.350032498338, 13003.544875929487, 13019.47922083125, 16551.586210344838, 19701.0, 19710.211591536463, 19742.802571974386, 19774.802251977297, 6778.457723382117, 8049.6377663005305, 8167.079801695219, 8354.707585234488, 8479.406441549032, 884.6859364925488, 981.7202097402206, 1024.784795193003, 1097.1935627313917, 1138.1975707126455, 30264.69735302663, 30366.696333036827, 30747.69252307493, 30801.0, 30936.535951960737, 1057.1859668055554, 1061.692109288313, 1069.561479793281, 1085.6688709943544, 1121.3552207480732, 13213.73572528544, 15916.840831591768, 15917.442889498812, 16380.590485237726, 16390.26243819031, 617648.0, 618608.0, 619176.0, 623727.8813605903, 628254.0, 52035.47964520382, 58990.41009589935, 60206.0, 60765.0, 60786.0, 1412.5268035208114, 1431.4131206205407, 1443.076431084107, 1449.6303442622063, 1449.6303442622275, 26698.332541686614, 31096.22259443532, 31712.0, 31757.36485270328, 32411.837940810146, 52303.476965229864, 52437.68905777386, 52564.73717631387, 52735.736321318145, 52741.0, 565651.0, 575117.0, 575150.0, 624454.0, 815934.0, 25686.486270274116, 26627.866860665574, 26782.330441739112, 26783.464330712886, 26800.061997829984, 46419.76790116028, 47328.0, 47689.28466072942, 48102.759486203744, 48309.51690483191, 38271.6172838268, 38746.0, 39183.80408097941, 39808.2038359237, 39847.203055939295, 38285.042873928374, 38505.614943851324, 38800.80599596984, 39584.81245562638, 40515.797421013885, 36774.26451471009, 37605.0, 38063.61936380711, 38158.0, 38203.42694859635, 26511.602325965512, 34172.65827341695, 34256.0, 34358.0, 34386.484202737476, 24787.50424991454, 24942.0, 25021.238318924883, 25129.623055653814, 25686.229413117635], "value": 20471.451721451103, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.61582061974076, 349.70449969775564, 367.40847235950116, 375.32628931068746, 400.41939188177133, 15641.608959775996, 17609.911950440244, 20523.589528209464, 21263.36209913701, 21272.8936355318, 21128.0, 21601.351959441203, 21950.0, 21961.121555137815, 22117.33647990559, 5303.734813259341, 5609.551235901128, 6043.728032238538, 6103.0, 6108.572399931984, 5940.6435613863305, 6000.699965001754, 6039.305479869828, 6049.879002419949, 6061.787837425691, 5754.16564598133, 5980.401959804007, 5996.97001514994, 6006.579539432241, 6010.459058684717, 5913.438223368773, 5930.614510056854, 5967.015442451991, 5967.671778052227, 5967.761289548413, 5104.157813960692, 5408.91886621701, 5412.945870541323, 5426.538744206747, 5427.511523962862, 12982.0, 13306.86693133066, 13447.66380840477, 18044.27822887083, 25703.61444578333, 2299.4251437140715, 2462.7783499484954, 2589.624588997824, 2638.511875303075, 2958.8921079617285, 9928.503574821303, 9975.90024099757, 10063.04401081896, 10302.330348527397, 10330.948345258226, 1757.261949981011, 1800.8469280111076, 1833.1384249402759, 1839.3194518028415, 1852.7128295114144, 81692.0, 113513.0, 114872.42563787212, 117374.82625173725, 122033.38983305116, 9401.623935042522, 10911.399873007062, 11040.944795276133, 11272.267302625303, 11290.579407257106, 14198.219097949504, 14241.85758142426, 14264.786028209584, 14320.641983950485, 14333.498327558485, 8495.575221238912, 8642.43824151428, 8679.0, 8770.166834150745, 8824.470531767985, 8060.395470339748, 8556.203197328507, 8557.400981931205, 8667.089955554577, 8672.60973256205, 4391.165678521071, 4441.267190913463, 4471.7987690554, 4500.437445319335, 4563.703359281636, 1756.1833747307307, 1872.7939926608062, 1897.3074827687794, 1898.3071179019557, 1907.9523011924534, 774.8605251054864, 957.7788319892193, 1011.4942528735633, 1018.7962407518526, 1076.4133547216786, 19187.040647967548, 20336.593268134555, 20405.0, 21535.35393938153, 21582.460438489165, 966.5505539923829, 982.5627595719836, 1010.2726037253166, 1032.726327523207, 1045.2265323660436, 9206.539673016323, 10760.19298552596, 11039.944800276107, 11235.83146252807, 11294.60468883585, 512875.0, 558446.0, 568121.0, 571874.0, 575115.0, 74586.0, 95936.0, 115068.42465787785, 124310.75689242991, 147887.26056369866, 914.7758799094131, 938.5072836760619, 949.392388871123, 951.0394501553343, 973.8295798235139, 18823.623527529646, 18935.242590296235, 19060.523486912938, 19148.617027659646, 19346.8065319345, 51012.489875102256, 52640.736796315774, 53409.0, 60685.0, 61743.0, 74842.0, 77327.22672773425, 79961.2003879977, 83452.58273708592, 86107.0, 7747.18654541265, 7997.880031799644, 8038.0, 8054.8791768122355, 8227.753167404988, 12913.677158071123, 12933.870661293267, 13006.739865202453, 13249.47002119943, 13411.262380569162, 11272.985431310894, 11416.657500275005, 11548.018418434527, 11692.94153529227, 11698.76602467963, 10269.589216431557, 11190.944045280046, 11328.207025508136, 11381.089512839118, 11609.071274297867, 11098.901098901099, 11422.31466112036, 11868.05055595533, 11906.642800715992, 11933.701657458632, 8314.459560128573, 8366.790830229293, 8381.916180838114, 8386.077531471412, 8534.146585341416, 11083.115870301906, 11576.536938522364, 11743.530258789553, 11783.646490605295, 11910.940445297718], "value": 9467.708146464516, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1569.8744100471963, 1600.4398460538812, 1802.9819701802978, 1920.3278852401652, 1935.303290815307, 6950.582965022089, 8564.443311184776, 9035.548222588877, 9050.456972581633, 9061.864072038916, 7794.259545343198, 8030.598470076502, 8036.8392632147325, 8184.0, 8758.649654013847, 2144.206643541888, 2285.394370491813, 2369.18263199196, 2382.809375249976, 2391.222852572912, 2104.4528422610156, 2340.8880781628677, 2370.525894821034, 2388.7014123234594, 2389.330987323552, 2087.1025459052603, 2366.715994080713, 2367.1241640593003, 2370.9288721338303, 2373.038919237703, 2271.977280227193, 2345.7185137783404, 2348.2250857217164, 2352.1061996441413, 2352.5765362234793, 2328.4877326988035, 2344.542814151241, 2349.6475528670667, 2351.318117745852, 2361.2089949866813, 7671.0, 7757.456978011542, 8891.0, 8924.955375223082, 8925.821483570293, 5197.298364720752, 5359.410464848864, 5367.2485851980755, 5367.543758780508, 5367.597430192712, 3439.8280085995602, 3519.3841077811535, 3581.3732596795717, 3591.856325746993, 3595.7303202259936, 1048.5124417146064, 1062.9946850265699, 1070.4433694478905, 1085.6580177244143, 1088.9128869690405, 11714.88285117155, 11764.647060588197, 11765.705857353549, 11771.234869733526, 11813.940930295379, 4461.420015397977, 4655.534446555317, 4730.219513780256, 4741.644376671763, 4750.334953106568, 8523.531205783742, 9781.90218097824, 9895.80208395828, 9942.353747006422, 9956.20350371983, 5341.145416733304, 5341.946580534222, 5345.4654534546235, 5351.973240133774, 5352.19717042439, 3952.407138929126, 4420.84527041552, 4907.141250281186, 4921.286413470027, 4928.211486162197, 3527.065327688165, 3698.68561172298, 3768.057985503624, 3801.2207497462955, 3806.5432148141845, 1134.733337665648, 1138.1407037686658, 1148.357848276972, 1148.6439203846835, 1153.6827372472637, 953.6519170502717, 958.3100167879115, 972.3436680240809, 988.2094324540338, 1004.5278719002057, 5949.791757288561, 5964.403559644001, 6098.298695649968, 6159.983602705593, 6229.719662615194, 928.4522131942051, 980.6562316064611, 985.8866230383454, 1005.1406047829001, 1025.1849779425374, 4049.6760259179196, 4140.91718165641, 4170.645495132887, 4194.454720886326, 4206.768627725443, 10233.334833235816, 10493.26547141708, 10669.19981001428, 10692.62575809843, 10897.564097436172, 10546.156307495383, 10673.306235094695, 10764.46177691128, 10771.407572583426, 10771.515281812337, 8081.151479094728, 8360.581970901309, 8369.62336694838, 8373.41386102979, 8391.286740627114, 5989.131575921554, 6083.087536869328, 6090.878182436415, 6234.438900498977, 6354.015127655221, 9451.0, 9861.457619830979, 10112.949435253071, 10115.190784737057, 10119.089281964658, 9864.506774661382, 10190.796184076426, 10226.386416815016, 10351.896481035094, 10353.327033742782, 6428.19647544057, 6949.51353405277, 7599.4680372373405, 7662.0, 7787.5716835574585, 7111.359977602041, 7834.960825195838, 8398.0, 8413.368997325348, 8486.19381158789, 7404.96297518509, 7587.355074818482, 7590.0891892973195, 7670.386369090348, 7763.68945242184, 7416.851662966603, 7426.962865185639, 7457.813554661177, 7494.775156745306, 7498.250174982458, 7076.150861896605, 7154.642267886481, 7157.964210178916, 7159.463040271896, 7165.7850264492145, 4987.451380348231, 5009.4489606144025, 5027.874303142451, 5153.123968925217, 5208.713520756395, 5084.0, 5084.237364395371, 5136.563392111712, 5175.120229560908, 5289.4710528948335], "value": 4596.966715505234, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.94301082794266, 301.4002135749858, 311.30889425475453, 315.47001038255723, 316.45253711079846, 4369.978150109253, 5461.535769459596, 5529.364123125832, 5561.833145005646, 5583.3579138399045, 5127.6413948863465, 5139.871503212422, 5301.973490132544, 5303.363596368432, 5499.940055149263, 3672.889813305605, 3745.3071181831447, 3763.322601931651, 3926.096997690533, 4108.760725404647, 3712.0905378182383, 3985.820638071279, 4205.222033923718, 4228.492580890297, 4252.0, 3869.0520822398553, 3942.56631770505, 4090.9590904090874, 4100.384942258655, 4151.273527132755, 3728.1425272187275, 3855.20968201518, 4110.650594699454, 4111.342185250345, 4147.025449019478, 3389.2204792897523, 3455.87904423344, 3492.511048453219, 3495.405781017233, 3509.2279698466295, 5256.290400795881, 5308.5487733542695, 5313.707746073965, 5317.202419637047, 5344.156266088194, 4727.692699974525, 4748.5251474852585, 4764.714117152982, 4780.235162374003, 4801.927971080437, 5322.0, 5324.733763311819, 5362.678239305654, 5517.0, 5786.334571524244, 3283.5074738789135, 3874.740709269499, 3900.0639846436934, 3902.790135058113, 3937.5471820740695, 5517.889642207174, 5938.524918006549, 6160.275122965532, 6172.179207133861, 6172.938270617281, 3352.580927384077, 3914.4146620618694, 3953.150072734376, 3967.7464479127416, 3973.4039894016128, 3551.5560554930635, 3926.155876486569, 4006.7595944242858, 4027.275090483741, 4032.9596704032583, 3821.38857782759, 4454.665900057508, 4525.343825145335, 4741.502142275081, 4745.928811067837, 5356.839294821161, 5866.919867069822, 5872.7357268923, 5884.705764711747, 5907.868829520361, 1286.75551645189, 1338.7188690374935, 1346.7171893902193, 1360.6258278973164, 1365.235468137846, 853.5390888920037, 874.1870060843477, 896.0949441064441, 924.9907500924904, 925.5511077127587, 5199.402068762065, 5482.314710661168, 5482.5888058394985, 5485.780568777204, 5508.394076651565, 5057.772400241998, 5259.421463638997, 5487.259220005328, 5625.774969001276, 5983.431574000463, 5220.851412689202, 5240.292560504283, 5370.62405631602, 5382.892342153214, 5432.066683990601, 2826.0, 3077.8922737704074, 3092.0, 3105.2392163919985, 3165.2561648012697, 5627.71861406928, 5727.885442291131, 5977.372375900555, 6083.6045657032155, 6160.537959652955, 5971.7611295547695, 6014.7594096236535, 6056.485198758067, 6106.938930610726, 6108.526046950992, 5337.599680024014, 5738.942610573924, 5798.652080875161, 6045.72794224261, 6088.0, 5225.216217567243, 5620.110503861666, 5856.326522449803, 5889.293284805851, 5916.378780228186, 4910.779014944264, 5543.556515478671, 5709.628874123168, 5787.710614469175, 5858.970705146447, 5522.502974732293, 5583.190437386637, 5714.599978001666, 6048.0, 6081.5742897996715, 3926.9214615708097, 3926.9607303926596, 3954.2517950024458, 3974.1852920150723, 3982.940255896106, 5252.711100889488, 5471.331243970627, 5935.940640593539, 5952.613080149777, 5952.613080149949, 4807.254875494304, 5333.916927377284, 5698.6580805151825, 5699.230603868425, 5758.379937128513, 5622.465865742748, 5699.373068962328, 5721.255017219788, 5725.541956643542, 5726.398728133652, 4827.73447460379, 5281.366236051545, 5518.42056584069, 5583.944160558505, 5640.32316122068, 4878.487758785276, 5583.078791999437, 5631.802886898939, 5729.885402292014, 5732.627379220337, 4755.524447555217, 5277.8416647500635, 5516.586256030733, 5567.164925261243, 5684.658920464785], "value": 4177.730830387167, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.3866344275644, 743.5352904434728, 744.2148533297371, 759.8404335089631, 766.7967988483053, 14446.27768611569, 16210.594735131628, 16234.188290585482, 16242.593935151626, 16245.593860153502, 14553.272336383165, 14666.413343466249, 14702.705945881076, 14710.411583536672, 14717.116972981628, 708.2209569473589, 725.0, 876.8597024476085, 879.8680197970308, 908.2007833106857, 888.8266787976347, 898.955052247385, 901.7159594727673, 902.309733054214, 902.5442151713376, 952.2905435450599, 952.3809523809545, 952.4666186935303, 953.5518306396017, 954.5513608603945, 736.6390468670339, 762.4891322813728, 762.6034462079743, 766.3600893254139, 948.2603569216022, 684.2985939412092, 702.1784512120831, 704.5526090932244, 707.2962402409584, 710.6127160697432, 15259.847401525954, 15301.311440985071, 15320.923395383063, 15331.003484773566, 15355.385784568603, 13635.863641363558, 13722.794158087636, 13801.378937947817, 13825.446982120704, 14085.366158522891, 12728.218049473082, 12743.681407964783, 12759.489620415265, 12940.288284144368, 13246.40391182399, 4505.887352816206, 4540.682152249344, 4585.335126406652, 4706.7646617669325, 4730.195866702668, 102883.48558257235, 103688.96311036868, 104824.47587762089, 104947.0, 104965.47517262441, 11016.889831101586, 11060.502277397536, 11097.611583594535, 11100.777984440267, 11390.772184556263, 21822.563548728936, 21882.343529694135, 21952.670709939364, 21959.451013724465, 22004.33986980393, 7685.269899359552, 7765.3011228988535, 7792.493487923267, 7913.723019694398, 7933.206679332021, 4387.166438376698, 4467.865964021084, 4472.485664148665, 4474.6420286377015, 4583.100250986374, 3276.8689252430113, 3386.779859309137, 3390.5931288245074, 3392.830358482066, 3393.2025973896116, 635.4852569418786, 640.2061443809642, 648.970796314167, 650.5673726971511, 653.2389765922742, 594.9167116603679, 631.0280373831775, 636.3636363636364, 652.5823472977298, 653.5947712418331, 8658.50014938746, 9024.142706442877, 9038.593263303035, 9049.592768325441, 9103.453792772454, 612.8896798576299, 622.7135517661835, 628.4438272129198, 636.8376064103624, 640.7116797441076, 4190.664746820247, 4322.15717935006, 4331.350297455344, 4383.919969249639, 4538.818447262072, 135709.0, 137109.0, 141913.5808641921, 148654.0, 148906.0, 97312.0, 127150.0, 127418.0, 128946.0, 132177.0, 83666.0, 106768.0, 107523.38714919283, 110590.34114488288, 113156.43421783003, 8507.914920850711, 8517.97784265892, 8525.488470691778, 8529.232369086812, 8863.645454182019, 62022.37977620166, 62426.0, 63346.0, 63515.6824215876, 65128.320015930345, 90318.0968190336, 93849.5307523458, 94291.0, 95257.57113643162, 96009.51995240258, 11573.884261157618, 11651.0, 11719.531218751496, 11804.645860624196, 12320.630381088582, 12082.516699331929, 12207.87792122103, 12316.568920087753, 12394.81407778902, 12913.612591622266, 11835.940820295844, 12044.337561434206, 12114.575989840665, 12324.383780810744, 12340.533722849139, 10696.0, 11720.355380454157, 11982.820257696316, 12009.459574319351, 12073.818892716792, 11691.824622630838, 11717.238379505305, 11724.179307448398, 11794.29234245948, 12127.939360303495, 9540.90459095428, 9771.75926619449, 10006.249531285037, 10194.184465242912, 10223.897761022592, 9782.510874456391, 10085.798284034425, 10107.949460252652, 10216.795664086529, 10263.692089237335], "value": 7632.5261701082545, "unit": "score"}], "name": "dromaeo_css", "value": 7386.135058102849, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 114.0, 113.0, 113.0, 114.0, 114.0, 113.0, 161.0, 113.0, 114.0], "value": 116.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 108.0, 131.0, 194.0, 134.0, 107.0, 131.0, 105.0, 138.0, 106.0], "value": 129.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 187.0, 193.0, 183.0, 182.0, 180.0, 181.0, 183.0, 183.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 77.0, 58.0, 107.0, 57.0, 57.0, 60.0, 60.0, 125.0, 67.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 89.0, 91.0, 71.0, 71.0, 67.0, 95.0, 70.0, 67.0, 99.0], "value": 80.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 91.0, 89.0, 91.0, 86.0, 90.0, 86.0, 89.0, 85.0], "value": 88.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 94.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 83.0, 84.0, 84.0, 86.0, 85.0, 85.0, 85.0, 92.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 55.0, 54.0, 55.0, 54.0, 56.0, 53.0, 54.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 68.0, 82.0, 71.0, 72.0, 70.0, 69.0, 70.0, 73.0], "value": 71.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 95.0, 102.0, 76.0, 84.0, 87.0, 84.0, 85.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 144.0, 145.0, 149.0, 137.0, 126.0, 138.0, 141.0, 226.0, 136.0], "value": 147.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 162.0, 161.0, 162.0, 161.0, 161.0, 162.0, 165.0, 159.0, 161.0], "value": 161.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 69.0, 66.0, 65.0, 64.0, 65.0, 66.0, 66.0, 64.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1440.3000000000002, "alertThreshold": 2.0}]} 10:46:47 INFO - Return code: 0 10:46:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:46:47 INFO - # TBPL SUCCESS # 10:46:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:46:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:46:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:46:47 INFO - 2016-07-11 10:46:47.346 screenresolution[1674:9623] starting screenresolution argv=screenresolution get 10:46:47 INFO - 2016-07-11 10:46:47.348 screenresolution[1674:9623] Display 0: 1600x1200x32@60 10:46:47 INFO - 2016-07-11 10:46:47.354 screenresolution[1675:9626] starting screenresolution argv=screenresolution list 10:46:47 INFO - Available Modes on Display 0 10:46:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:46:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:46:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:46:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:46:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:46:47 INFO - Intel Iris: 10:46:47 INFO - Chipset Model: Intel Iris 10:46:47 INFO - Type: GPU 10:46:47 INFO - Bus: Built-In 10:46:47 INFO - VRAM (Dynamic, Max): 1536 MB 10:46:47 INFO - Vendor: Intel (0x8086) 10:46:47 INFO - Device ID: 0x0a2e 10:46:47 INFO - Revision ID: 0x0009 10:46:47 INFO - Displays: 10:46:47 INFO - Display: 10:46:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:46:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:46:47 INFO - Display Serial Number: 1600x1200 60 10:46:47 INFO - Main Display: Yes 10:46:47 INFO - Mirror: Off 10:46:47 INFO - Online: Yes 10:46:47 INFO - Rotation: Supported 10:46:47 INFO - Return code: 0 10:46:47 INFO - Running post-action listener: _resource_record_post_action 10:46:47 INFO - ##### 10:46:47 INFO - ##### Finished run-tests step (success) 10:46:47 INFO - ##### 10:46:47 INFO - Running post-run listener: _resource_record_post_run 10:46:47 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 32570880; Write bytes: 704914432; Read time: 322; Write time: 1958 10:46:47 INFO - TinderboxPrint: CPU usage
53.5% 10:46:47 INFO - TinderboxPrint: I/O read bytes / time
32,570,880 / 322 10:46:47 INFO - TinderboxPrint: I/O write bytes / time
704,914,432 / 1,958 10:46:47 INFO - TinderboxPrint: CPU idle
2,252.3 (46.5%) 10:46:47 INFO - TinderboxPrint: CPU system
962.6 (19.9%) 10:46:47 INFO - TinderboxPrint: CPU user
1,630.6 (33.7%) 10:46:47 INFO - TinderboxPrint: Swap in / out
434,642,944 / 0 10:46:47 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 407235072; Write bytes: 410871808; Read time: 30634; Write time: 881 10:46:47 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 29232640; Write bytes: 292568064; Read time: 293; Write time: 1070 10:46:48 INFO - Running post-run listener: _upload_blobber_files 10:46:48 INFO - Blob upload gear active. 10:46:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:46:48 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 10:46:48 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'] 10:46:48 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 10:46:48 INFO - (blobuploader) - INFO - Open directory for files ... 10:46:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:46:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:46:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:46:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:46:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:46:49 INFO - (blobuploader) - INFO - Done attempting. 10:46:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:46:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:46:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:46:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:46:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:46:50 INFO - (blobuploader) - INFO - Done attempting. 10:46:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:46:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:46:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:46:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:46:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:46:53 INFO - (blobuploader) - INFO - Done attempting. 10:46:53 INFO - (blobuploader) - INFO - Iteration through files over. 10:46:53 INFO - Return code: 0 10:46:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:46:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:46:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7758e069e668bbd794b5fffa3236369564a4d444648f0d4fc839db2443a16df123ca752fb12917ad27ae4d39a2b56639c09de2d2f6123ddce87710443e4bf37f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0dc95c0e9b4e7916e55b34a6217cfa2c1387c61aec3c70f43dce5196e5b3d290950d0f751b638ec963d7961963a68463930af1ddc96420d5bb8910f3cb5c1595", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e23d8ce9a2106dc91b18da83652871676ac35f1c452b39db0e9ce132256692ddc28a1116f9a20d2884ec20d3a56ff377457132e7037e5c6abda328a46b615b9d"} 10:46:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:46:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:46:53 INFO - Contents: 10:46:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7758e069e668bbd794b5fffa3236369564a4d444648f0d4fc839db2443a16df123ca752fb12917ad27ae4d39a2b56639c09de2d2f6123ddce87710443e4bf37f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0dc95c0e9b4e7916e55b34a6217cfa2c1387c61aec3c70f43dce5196e5b3d290950d0f751b638ec963d7961963a68463930af1ddc96420d5bb8910f3cb5c1595", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e23d8ce9a2106dc91b18da83652871676ac35f1c452b39db0e9ce132256692ddc28a1116f9a20d2884ec20d3a56ff377457132e7037e5c6abda328a46b615b9d"} 10:46:53 INFO - Running post-run listener: copy_logs_to_upload_dir 10:46:53 INFO - Copying logs to upload dir... 10:46:53 INFO - mkdir: /builds/slave/test/build/upload/logs 10:46:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1298.721939 ========= master_lag: 0.20 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 38 secs) (at 2016-07-11 10:46:53.411829) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 10:46:53.415105) ========= 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.sGcDOxx4Ep/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.7UK0VQaA24/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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/7758e069e668bbd794b5fffa3236369564a4d444648f0d4fc839db2443a16df123ca752fb12917ad27ae4d39a2b56639c09de2d2f6123ddce87710443e4bf37f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0dc95c0e9b4e7916e55b34a6217cfa2c1387c61aec3c70f43dce5196e5b3d290950d0f751b638ec963d7961963a68463930af1ddc96420d5bb8910f3cb5c1595", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e23d8ce9a2106dc91b18da83652871676ac35f1c452b39db0e9ce132256692ddc28a1116f9a20d2884ec20d3a56ff377457132e7037e5c6abda328a46b615b9d"} build_url:https://queue.taskcluster.net/v1/task/TCxCaKK0QY-WMjP76UBAZg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010948 build_url: 'https://queue.taskcluster.net/v1/task/TCxCaKK0QY-WMjP76UBAZg/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/7758e069e668bbd794b5fffa3236369564a4d444648f0d4fc839db2443a16df123ca752fb12917ad27ae4d39a2b56639c09de2d2f6123ddce87710443e4bf37f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0dc95c0e9b4e7916e55b34a6217cfa2c1387c61aec3c70f43dce5196e5b3d290950d0f751b638ec963d7961963a68463930af1ddc96420d5bb8910f3cb5c1595", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e23d8ce9a2106dc91b18da83652871676ac35f1c452b39db0e9ce132256692ddc28a1116f9a20d2884ec20d3a56ff377457132e7037e5c6abda328a46b615b9d"}' ========= master_lag: 0.05 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-11 10:46:53.475814) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 10:46:53.476773) ========= 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.sGcDOxx4Ep/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.7UK0VQaA24/Listeners TMPDIR=/var/folders/ng/0q61k9nx7_qgv5x7bgjycqqw00000w/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.005076 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-11 10:46:53.529709) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 10:46:53.530454) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-11 10:46:53.531122) ========= ========= Total master_lag: 0.38 =========