builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0016 starttime: 1467736650.52 results: success (0) buildid: 20160705070325 builduid: 6e16db3125f34e1e86cd054f1bfbc51f revision: b2e48cc9d3a02fc172910ce8b29f83c19db20777 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 09:37:30.524871) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 09:37:30.525321) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 09:37:30.525599) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 09:37:30.571224) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-05 09:37:30.571513) ========= 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.ggboIhxFml/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.amvnHJBo8U/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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-05 09:37:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.97M=0.001s 2016-07-05 09:37:30 (8.97 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.096994 ========= master_lag: 2.55 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-05 09:37:33.222682) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:37:33.222962) ========= 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.ggboIhxFml/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.amvnHJBo8U/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.028441 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:37:33.272381) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-05 09:37:33.272690) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b2e48cc9d3a02fc172910ce8b29f83c19db20777 --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 b2e48cc9d3a02fc172910ce8b29f83c19db20777 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ggboIhxFml/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.amvnHJBo8U/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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-05 09:37:33,345 truncating revision to first 12 chars 2016-07-05 09:37:33,345 Setting DEBUG logging. 2016-07-05 09:37:33,345 attempt 1/10 2016-07-05 09:37:33,346 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b2e48cc9d3a0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-05 09:37:34,976 unpacking tar archive at: mozilla-central-b2e48cc9d3a0/testing/mozharness/ program finished with exit code 0 elapsedTime=2.008806 ========= master_lag: 0.15 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-05 09:37:35.434508) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 09:37:35.434813) ========= script_repo_revision: b2e48cc9d3a02fc172910ce8b29f83c19db20777 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 09:37:35.435270) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 09:37:35.435546) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 09:37:35.454608) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-05 09:37:35.455120) ========= /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.ggboIhxFml/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.amvnHJBo8U/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:37:35 INFO - MultiFileLogger online at 20160705 09:37:35 in /builds/slave/test 09:37:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:37:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:37:35 INFO - {'append_to_log': False, 09:37:35 INFO - 'base_work_dir': '/builds/slave/test', 09:37:35 INFO - 'blob_upload_branch': 'Firefox', 09:37:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:37:35 INFO - 'branch': 'Firefox', 09:37:35 INFO - 'buildbot_json_path': 'buildprops.json', 09:37:35 INFO - 'config_files': ('talos/mac_config.py',), 09:37:35 INFO - 'default_actions': ('clobber', 09:37:35 INFO - 'read-buildbot-config', 09:37:35 INFO - 'download-and-extract', 09:37:35 INFO - 'populate-webroot', 09:37:35 INFO - 'create-virtualenv', 09:37:35 INFO - 'install', 09:37:35 INFO - 'run-tests'), 09:37:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:37:35 INFO - 'download_minidump_stackwalk': True, 09:37:35 INFO - 'download_symbols': 'ondemand', 09:37:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:37:35 INFO - 'tooltool.py': '/tools/tooltool.py', 09:37:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:37:35 INFO - '/tools/misc-python/virtualenv.py')}, 09:37:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:37:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:37:35 INFO - 'installer_path': 'installer.exe', 09:37:35 INFO - 'log_level': 'info', 09:37:35 INFO - 'log_name': 'talos', 09:37:35 INFO - 'log_to_console': True, 09:37:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:37:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:37:35 INFO - 'opt_config_files': (), 09:37:35 INFO - 'pip_index': False, 09:37:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:37:35 INFO - 'cmd': ('bash', 09:37:35 INFO - '-c', 09:37:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:37:35 INFO - 'enabled': True, 09:37:35 INFO - 'halt_on_failure': False, 09:37:35 INFO - 'name': 'check_screen_resolution'},), 09:37:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:37:35 INFO - 'cmd': ('bash', 09:37:35 INFO - '-c', 09:37:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:37:35 INFO - 'enabled': True, 09:37:35 INFO - 'halt_on_failure': False, 09:37:35 INFO - 'name': 'check_screen_resolution'},), 09:37:35 INFO - 'run_cmd_checks_enabled': True, 09:37:35 INFO - 'sps_profile': False, 09:37:35 INFO - 'sps_profile_interval': 0, 09:37:35 INFO - 'suite': 'dromaeojs', 09:37:35 INFO - 'system_bits': '32', 09:37:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:37:35 INFO - 'title': 't-yosemite-r7-0016', 09:37:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:37:35 INFO - 'use_talos_json': True, 09:37:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:37:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:37:35 INFO - 'work_dir': 'build'} 09:37:35 INFO - ##### 09:37:35 INFO - ##### Running clobber step. 09:37:35 INFO - ##### 09:37:35 INFO - Running pre-action listener: _resource_record_pre_action 09:37:35 INFO - Running main action method: clobber 09:37:35 INFO - rmtree: /builds/slave/test/build 09:37:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:37:37 INFO - Running post-action listener: _resource_record_post_action 09:37:37 INFO - ##### 09:37:37 INFO - ##### Finished clobber step (success) 09:37:37 INFO - ##### 09:37:37 INFO - ##### 09:37:37 INFO - ##### Running read-buildbot-config step. 09:37:37 INFO - ##### 09:37:37 INFO - Running pre-action listener: _resource_record_pre_action 09:37:37 INFO - Running main action method: read_buildbot_config 09:37:37 INFO - Using buildbot properties: 09:37:37 INFO - { 09:37:37 INFO - "project": "", 09:37:37 INFO - "product": "firefox", 09:37:37 INFO - "script_repo_revision": "production", 09:37:37 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:37:37 INFO - "repository": "", 09:37:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:37:37 INFO - "buildid": "20160705070325", 09:37:37 INFO - "pgo_build": "False", 09:37:37 INFO - "basedir": "/builds/slave/test", 09:37:37 INFO - "buildnumber": 278, 09:37:37 INFO - "slavename": "t-yosemite-r7-0016", 09:37:37 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:37:37 INFO - "platform": "yosemite_r7", 09:37:37 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:37:37 INFO - "branch": "mozilla-central", 09:37:37 INFO - "repo_path": "mozilla-central", 09:37:37 INFO - "revision": "b2e48cc9d3a02fc172910ce8b29f83c19db20777", 09:37:37 INFO - "stage_platform": "macosx64", 09:37:37 INFO - "builduid": "6e16db3125f34e1e86cd054f1bfbc51f", 09:37:37 INFO - "slavebuilddir": "test" 09:37:37 INFO - } 09:37:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:37:37 INFO - Running post-action listener: _resource_record_post_action 09:37:37 INFO - ##### 09:37:37 INFO - ##### Finished read-buildbot-config step (success) 09:37:37 INFO - ##### 09:37:37 INFO - ##### 09:37:37 INFO - ##### Running download-and-extract step. 09:37:37 INFO - ##### 09:37:37 INFO - Running pre-action listener: _resource_record_pre_action 09:37:37 INFO - Running main action method: download_and_extract 09:37:37 INFO - mkdir: /builds/slave/test/build/tests 09:37:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:37 INFO - https://queue.taskcluster.net/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:37:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:37:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:37:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/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 09:37:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/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 09:37:38 INFO - Downloaded 1199 bytes. 09:37:38 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:37:38 INFO - Using the following test package requirements: 09:37:38 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:37:38 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:37:38 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:37:38 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:37:38 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:37:38 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:37:38 INFO - u'jsshell-mac.zip'], 09:37:38 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:37:38 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:37:38 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:37:38 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:37:38 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:37:38 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:37:38 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:37:38 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:37:38 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:37:38 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:37:38 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:37:38 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:37:38 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')]} 09:37:38 INFO - https://queue.taskcluster.net/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:37:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:37:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:37:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/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 09:37:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/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 09:37:39 INFO - Downloaded 18800237 bytes. 09:37:39 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'] 09:37:39 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 09:37:43 INFO - Return code: 0 09:37:43 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 09:37:43 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')]} 09:37:43 INFO - https://queue.taskcluster.net/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:37:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:37:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:37:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/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 09:37:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/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 09:37:45 INFO - Downloaded 18800237 bytes. 09:37:45 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'] 09:37:45 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 09:37:49 INFO - Return code: 0 09:37:49 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')]} 09:37:49 INFO - https://queue.taskcluster.net/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:37:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:37:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:37:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/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 09:37:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/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 09:37:50 INFO - Downloaded 14403333 bytes. 09:37:50 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'] 09:37:50 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 09:37:50 INFO - Return code: 0 09:37: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')]} 09:37:50 INFO - https://queue.taskcluster.net/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:37:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:37:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:37:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:37:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JhZjf3CIQLuQT9IaeDlOqw/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 09:37:53 INFO - Downloaded 112450202 bytes. 09:37:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:37:53 INFO - mkdir: /builds/slave/test/properties 09:37:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:37:53 INFO - Writing to file /builds/slave/test/properties/build_url 09:37:53 INFO - Contents: 09:37:53 INFO - build_url:https://queue.taskcluster.net/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:37:54 INFO - Running post-action listener: _resource_record_post_action 09:37:54 INFO - Running post-action listener: set_extra_try_arguments 09:37:54 INFO - ##### 09:37:54 INFO - ##### Finished download-and-extract step (success) 09:37:54 INFO - ##### 09:37:54 INFO - ##### 09:37:54 INFO - ##### Running populate-webroot step. 09:37:54 INFO - ##### 09:37:54 INFO - Running pre-action listener: _resource_record_pre_action 09:37:54 INFO - Running main action method: populate_webroot 09:37:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:37:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:37:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:37:54 INFO - '--tppagecycles', 09:37:54 INFO - '7'], 09:37:54 INFO - 'tests': ['tsvgm']}}, 09:37:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:37:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:37:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:37:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:37:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:37:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:37:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:54 INFO - 'talos_options': ['--disable-e10s'], 09:37:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:37:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:54 INFO - 'talos_options': ['--disable-e10s'], 09:37:54 INFO - 'tests': ['damp', 'tps']}, 09:37:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:54 INFO - 'tests': ['damp', 'tps']}, 09:37:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:37:54 INFO - 'tests': ['dromaeo_dom']}, 09:37:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:37:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:37:54 INFO - 'tests': ['basic_compositor_video']}, 09:37:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:37:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:37:54 INFO - 'tests': ['a11yr', 09:37:54 INFO - 'ts_paint', 09:37:54 INFO - 'tpaint', 09:37:54 INFO - 'sessionrestore', 09:37:54 INFO - 'sessionrestore_no_auto_restore', 09:37:54 INFO - 'tabpaint']}, 09:37:54 INFO - 'other-e10s': {'tests': ['a11yr', 09:37:54 INFO - 'ts_paint', 09:37:54 INFO - 'tpaint', 09:37:54 INFO - 'sessionrestore', 09:37:54 INFO - 'sessionrestore_no_auto_restore', 09:37:54 INFO - 'tabpaint']}, 09:37:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:37:54 INFO - 'ts_paint', 09:37:54 INFO - 'tpaint', 09:37:54 INFO - 'sessionrestore', 09:37:54 INFO - 'sessionrestore_no_auto_restore', 09:37:54 INFO - 'tabpaint']}, 09:37:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:37:54 INFO - 'ts_paint', 09:37:54 INFO - 'tpaint', 09:37:54 INFO - 'sessionrestore', 09:37:54 INFO - 'sessionrestore_no_auto_restore', 09:37:54 INFO - 'tabpaint']}, 09:37:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:37:54 INFO - 'tests': ['a11yr', 09:37:54 INFO - 'ts_paint', 09:37:54 INFO - 'tpaint', 09:37:54 INFO - 'sessionrestore', 09:37:54 INFO - 'sessionrestore_no_auto_restore', 09:37:54 INFO - 'tabpaint']}, 09:37:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:37:54 INFO - 'tests': ['a11yr', 09:37:54 INFO - 'ts_paint', 09:37:54 INFO - 'tpaint', 09:37:54 INFO - 'sessionrestore', 09:37:54 INFO - 'sessionrestore_no_auto_restore', 09:37:54 INFO - 'tabpaint']}, 09:37:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:37:54 INFO - 'tests': ['tsvgx', 09:37:54 INFO - 'tsvgr_opacity', 09:37:54 INFO - 'tart', 09:37:54 INFO - 'tscrollx', 09:37:54 INFO - 'cart']}, 09:37:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:37:54 INFO - 'tsvgr_opacity', 09:37:54 INFO - 'tart', 09:37:54 INFO - 'tscrollx', 09:37:54 INFO - 'cart']}, 09:37:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:54 INFO - 'talos_options': ['--disable-e10s'], 09:37:54 INFO - 'tests': ['tp5o']}, 09:37:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:54 INFO - 'tests': ['tp5o']}, 09:37:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:54 INFO - 'talos_options': ['--disable-e10s', 09:37:54 INFO - '--xperf_path', 09:37:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:54 INFO - 'tests': ['tp5n']}, 09:37:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:37:54 INFO - 'talos_options': ['--xperf_path', 09:37:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:37:54 INFO - 'tests': ['tp5n']}}, 09:37:54 INFO - 'talos.zip': {'path': '', 09:37:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:37:54 INFO - Running post-action listener: _resource_record_post_action 09:37:54 INFO - ##### 09:37:54 INFO - ##### Finished populate-webroot step (success) 09:37:54 INFO - ##### 09:37:54 INFO - ##### 09:37:54 INFO - ##### Running create-virtualenv step. 09:37:54 INFO - ##### 09:37:54 INFO - Running pre-action listener: _resource_record_pre_action 09:37:54 INFO - Running main action method: create_virtualenv 09:37:54 INFO - Creating virtualenv /builds/slave/test/build/venv 09:37:54 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:37:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:37:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:37:54 INFO - Using real prefix '/tools/python27' 09:37:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:37:55 INFO - Installing distribute.............................................................................................................................................................................................done. 09:37:58 INFO - Installing pip.................done. 09:37:58 INFO - Return code: 0 09:37:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:58 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 0x1015a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10163d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8eb1cc56c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10150cd30>, '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 0x101271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101504b20>, '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.ggboIhxFml/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.amvnHJBo8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:37:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:37:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggboIhxFml/Render', 09:37:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:58 INFO - 'HOME': '/Users/cltbld', 09:37:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:58 INFO - 'LOGNAME': 'cltbld', 09:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:58 INFO - 'MOZ_NO_REMOTE': '1', 09:37:58 INFO - 'NO_EM_RESTART': '1', 09:37:58 INFO - 'PAGER': '/bin/cat', 09:37:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:58 INFO - 'PWD': '/builds/slave/test', 09:37:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:58 INFO - 'SHELL': '/bin/bash', 09:37:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.amvnHJBo8U/Listeners', 09:37:58 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 09:37:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:58 INFO - 'USER': 'cltbld', 09:37:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:58 INFO - 'XPC_FLAGS': '0x0', 09:37:58 INFO - 'XPC_SERVICE_NAME': '0', 09:37:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:37:58 INFO - Cleaning up... 09:37:58 INFO - Return code: 0 09:37:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:37:58 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 0x1015a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10163d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8eb1cc56c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10150cd30>, '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 0x101271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101504b20>, '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.ggboIhxFml/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.amvnHJBo8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:37:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:37:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:37:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggboIhxFml/Render', 09:37:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:37:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:37:58 INFO - 'HOME': '/Users/cltbld', 09:37:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:37:58 INFO - 'LOGNAME': 'cltbld', 09:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:37:58 INFO - 'MOZ_NO_REMOTE': '1', 09:37:58 INFO - 'NO_EM_RESTART': '1', 09:37:58 INFO - 'PAGER': '/bin/cat', 09:37:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:37:58 INFO - 'PWD': '/builds/slave/test', 09:37:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:37:58 INFO - 'SHELL': '/bin/bash', 09:37:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.amvnHJBo8U/Listeners', 09:37:58 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 09:37:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:37:58 INFO - 'USER': 'cltbld', 09:37:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:37:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:37:58 INFO - 'XPC_FLAGS': '0x0', 09:37:58 INFO - 'XPC_SERVICE_NAME': '0', 09:37:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:37:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:37:59 INFO - Downloading/unpacking psutil>=3.1.1 09:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:37:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:37:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:38:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:38:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:38:01 INFO - Installing collected packages: psutil 09:38:01 INFO - Running setup.py install for psutil 09:38:01 INFO - building 'psutil._psutil_osx' extension 09:38:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:38:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:38:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:38:02 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:38:02 INFO - building 'psutil._psutil_posix' extension 09:38:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:38:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:38:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:38:02 INFO - ^ 09:38:02 INFO - 1 warning generated. 09:38:02 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:38:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:38:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:38:02 INFO - Successfully installed psutil 09:38:02 INFO - Cleaning up... 09:38:02 INFO - Return code: 0 09:38:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:38:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:38:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:38:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:02 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 0x1015a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10163d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8eb1cc56c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10150cd30>, '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 0x101271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101504b20>, '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.ggboIhxFml/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.amvnHJBo8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:38:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:38:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggboIhxFml/Render', 09:38:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:02 INFO - 'HOME': '/Users/cltbld', 09:38:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:02 INFO - 'LOGNAME': 'cltbld', 09:38:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:02 INFO - 'MOZ_NO_REMOTE': '1', 09:38:02 INFO - 'NO_EM_RESTART': '1', 09:38:02 INFO - 'PAGER': '/bin/cat', 09:38:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:02 INFO - 'PWD': '/builds/slave/test', 09:38:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:02 INFO - 'SHELL': '/bin/bash', 09:38:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.amvnHJBo8U/Listeners', 09:38:02 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 09:38:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:02 INFO - 'USER': 'cltbld', 09:38:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:02 INFO - 'XPC_FLAGS': '0x0', 09:38:02 INFO - 'XPC_SERVICE_NAME': '0', 09:38:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:38:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:38:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:38:05 INFO - Installing collected packages: mozsystemmonitor 09:38:05 INFO - Running setup.py install for mozsystemmonitor 09:38:05 INFO - Successfully installed mozsystemmonitor 09:38:05 INFO - Cleaning up... 09:38:05 INFO - Return code: 0 09:38:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:38:05 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')]} 09:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:05 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')]} 09:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:05 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 0x1015a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10163d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8eb1cc56c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10150cd30>, '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 0x101271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101504b20>, '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.ggboIhxFml/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.amvnHJBo8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:38:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:38:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggboIhxFml/Render', 09:38:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:05 INFO - 'HOME': '/Users/cltbld', 09:38:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:05 INFO - 'LOGNAME': 'cltbld', 09:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:05 INFO - 'MOZ_NO_REMOTE': '1', 09:38:05 INFO - 'NO_EM_RESTART': '1', 09:38:05 INFO - 'PAGER': '/bin/cat', 09:38:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:05 INFO - 'PWD': '/builds/slave/test', 09:38:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:05 INFO - 'SHELL': '/bin/bash', 09:38:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.amvnHJBo8U/Listeners', 09:38:05 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 09:38:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:05 INFO - 'USER': 'cltbld', 09:38:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:05 INFO - 'XPC_FLAGS': '0x0', 09:38:05 INFO - 'XPC_SERVICE_NAME': '0', 09:38:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:06 INFO - Downloading/unpacking jsonschema==2.5.1 09:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:38:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:09 INFO - Downloading functools32-3.2.3-2.tar.gz 09:38:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:38:09 INFO - warning: no files found matching '*.txt' 09:38:09 INFO - no previously-included directories found matching 'build' 09:38:09 INFO - no previously-included directories found matching 'dist' 09:38:09 INFO - no previously-included directories found matching '.git*' 09:38:09 INFO - Installing collected packages: jsonschema, functools32 09:38:09 INFO - Running setup.py install for functools32 09:38:09 INFO - warning: no files found matching '*.txt' 09:38:09 INFO - no previously-included directories found matching 'build' 09:38:09 INFO - no previously-included directories found matching 'dist' 09:38:09 INFO - no previously-included directories found matching '.git*' 09:38:09 INFO - Successfully installed jsonschema functools32 09:38:09 INFO - Cleaning up... 09:38:09 INFO - Return code: 0 09:38:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:38:09 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')]} 09:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:09 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')]} 09:38:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:09 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 0x1015a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10163d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8eb1cc56c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10150cd30>, '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 0x101271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101504b20>, '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.ggboIhxFml/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.amvnHJBo8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:38:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:38:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggboIhxFml/Render', 09:38:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:09 INFO - 'HOME': '/Users/cltbld', 09:38:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:09 INFO - 'LOGNAME': 'cltbld', 09:38:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:09 INFO - 'MOZ_NO_REMOTE': '1', 09:38:09 INFO - 'NO_EM_RESTART': '1', 09:38:09 INFO - 'PAGER': '/bin/cat', 09:38:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:09 INFO - 'PWD': '/builds/slave/test', 09:38:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:09 INFO - 'SHELL': '/bin/bash', 09:38:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.amvnHJBo8U/Listeners', 09:38:09 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 09:38:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:09 INFO - 'USER': 'cltbld', 09:38:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:09 INFO - 'XPC_FLAGS': '0x0', 09:38:09 INFO - 'XPC_SERVICE_NAME': '0', 09:38:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:09 INFO - Downloading/unpacking blobuploader==1.2.4 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38: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 09:38:12 INFO - Downloading blobuploader-1.2.4.tar.gz 09:38:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:38:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:38:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:13 INFO - Downloading docopt-0.6.1.tar.gz 09:38:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:38:13 INFO - Installing collected packages: blobuploader, requests, docopt 09:38:13 INFO - Running setup.py install for blobuploader 09:38:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:38:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:38:13 INFO - Running setup.py install for requests 09:38:14 INFO - Running setup.py install for docopt 09:38:14 INFO - Successfully installed blobuploader requests docopt 09:38:14 INFO - Cleaning up... 09:38:14 INFO - Return code: 0 09:38:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:14 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 0x1015a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10163d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8eb1cc56c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10150cd30>, '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 0x101271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101504b20>, '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.ggboIhxFml/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.amvnHJBo8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:38:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:38:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggboIhxFml/Render', 09:38:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:14 INFO - 'HOME': '/Users/cltbld', 09:38:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:14 INFO - 'LOGNAME': 'cltbld', 09:38:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:14 INFO - 'MOZ_NO_REMOTE': '1', 09:38:14 INFO - 'NO_EM_RESTART': '1', 09:38:14 INFO - 'PAGER': '/bin/cat', 09:38:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:14 INFO - 'PWD': '/builds/slave/test', 09:38:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:14 INFO - 'SHELL': '/bin/bash', 09:38:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.amvnHJBo8U/Listeners', 09:38:14 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 09:38:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:14 INFO - 'USER': 'cltbld', 09:38:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:14 INFO - 'XPC_FLAGS': '0x0', 09:38:14 INFO - 'XPC_SERVICE_NAME': '0', 09:38:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:38:14 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-KRFNUK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:38:14 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-TdxRKx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:38:14 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-jydxV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:38:14 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-qtZTeM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:38:15 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-2XnTc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:38:15 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-QSBNhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:38:15 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-pAndhq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:38:15 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-McNo_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:38:15 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-TylCEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:38:15 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-SF77t5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:38:15 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Zz54BN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:38:15 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-VFu1hl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:38:15 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-8m1_UT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:38:15 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-iLbFWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:16 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-V8bj44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:38:16 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-synmy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:38:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:38:16 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-QVKayx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:38:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:38:16 INFO - Running setup.py install for manifestparser 09:38:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:38:16 INFO - Running setup.py install for mozcrash 09:38:16 INFO - Running setup.py install for mozdebug 09:38:16 INFO - Running setup.py install for mozdevice 09:38:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:38:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:38:17 INFO - Running setup.py install for mozfile 09:38:17 INFO - Running setup.py install for mozhttpd 09:38:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:38:17 INFO - Running setup.py install for mozinfo 09:38:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:38:17 INFO - Running setup.py install for mozInstall 09:38:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:38:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:38:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:38:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:38:17 INFO - Running setup.py install for mozleak 09:38:17 INFO - Running setup.py install for mozlog 09:38:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:38:17 INFO - Running setup.py install for moznetwork 09:38:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:38:18 INFO - Running setup.py install for mozprocess 09:38:18 INFO - Running setup.py install for mozprofile 09:38:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:38:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:38:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:38:18 INFO - Running setup.py install for mozrunner 09:38:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:38:18 INFO - Running setup.py install for mozscreenshot 09:38:18 INFO - Running setup.py install for moztest 09:38:18 INFO - Running setup.py install for mozversion 09:38:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:38:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:38:18 INFO - Cleaning up... 09:38:18 INFO - Return code: 0 09:38:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:38:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:18 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 0x1015a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10163d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8eb1cc56c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10150cd30>, '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 0x101271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101504b20>, '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.ggboIhxFml/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.amvnHJBo8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:38:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:38:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggboIhxFml/Render', 09:38:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:18 INFO - 'HOME': '/Users/cltbld', 09:38:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:18 INFO - 'LOGNAME': 'cltbld', 09:38:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:18 INFO - 'MOZ_NO_REMOTE': '1', 09:38:18 INFO - 'NO_EM_RESTART': '1', 09:38:18 INFO - 'PAGER': '/bin/cat', 09:38:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:18 INFO - 'PWD': '/builds/slave/test', 09:38:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:18 INFO - 'SHELL': '/bin/bash', 09:38:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.amvnHJBo8U/Listeners', 09:38:18 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 09:38:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:18 INFO - 'USER': 'cltbld', 09:38:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:18 INFO - 'XPC_FLAGS': '0x0', 09:38:18 INFO - 'XPC_SERVICE_NAME': '0', 09:38:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:38:19 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-Hi1ruI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:38:19 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-bdOytI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:38:19 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-ld6IoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:38:19 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-NLr7pc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:38:19 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-ekRzL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:38:19 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-jiYMaT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:38:19 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-muFhm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:38:19 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-TR0Fu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:38:20 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-yTv08W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:38:20 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-OjyE3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:38:20 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)) 09:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:38:20 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-ibVkCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:38:20 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-WXniV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:38:20 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-gAr_o6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:38:20 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-oFyuWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:20 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-1di2Iz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:38:20 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-ocuNd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:38:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:38:20 INFO - Running setup.py (path:/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/pip-jpeiP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:38:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:38:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:38:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:38:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:38:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:38:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:38:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:38:23 INFO - Downloading blessings-1.6.tar.gz 09:38:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:38:23 INFO - Installing collected packages: blessings 09:38:23 INFO - Running setup.py install for blessings 09:38:23 INFO - Successfully installed blessings 09:38:23 INFO - Cleaning up... 09:38:23 INFO - Return code: 0 09:38:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:38:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:24 INFO - Reading from file tmpfile_stdout 09:38:24 INFO - Current package versions: 09:38:24 INFO - blessings == 1.6 09:38:24 INFO - blobuploader == 1.2.4 09:38:24 INFO - docopt == 0.6.1 09:38:24 INFO - functools32 == 3.2.3-2 09:38:24 INFO - jsonschema == 2.5.1 09:38:24 INFO - manifestparser == 1.1 09:38:24 INFO - mozInstall == 1.12 09:38:24 INFO - mozcrash == 0.17 09:38:24 INFO - mozdebug == 0.1 09:38:24 INFO - mozdevice == 0.48 09:38:24 INFO - mozfile == 1.2 09:38:24 INFO - mozhttpd == 0.7 09:38:24 INFO - mozinfo == 0.9 09:38:24 INFO - mozleak == 0.1 09:38:24 INFO - mozlog == 3.2 09:38:24 INFO - moznetwork == 0.27 09:38:24 INFO - mozprocess == 0.22 09:38:24 INFO - mozprofile == 0.28 09:38:24 INFO - mozrunner == 6.11 09:38:24 INFO - mozscreenshot == 0.1 09:38:24 INFO - mozsystemmonitor == 0.3 09:38:24 INFO - moztest == 0.7 09:38:24 INFO - mozversion == 1.4 09:38:24 INFO - psutil == 3.1.1 09:38:24 INFO - requests == 1.2.3 09:38:24 INFO - wsgiref == 0.1.2 09:38:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:38:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:38:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:38:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:24 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 0x1015a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10163d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8eb1cc56c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10150cd30>, '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 0x101271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101504b20>, '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.ggboIhxFml/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.amvnHJBo8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:38:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:38:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggboIhxFml/Render', 09:38:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:24 INFO - 'HOME': '/Users/cltbld', 09:38:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:24 INFO - 'LOGNAME': 'cltbld', 09:38:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:24 INFO - 'MOZ_NO_REMOTE': '1', 09:38:24 INFO - 'NO_EM_RESTART': '1', 09:38:24 INFO - 'PAGER': '/bin/cat', 09:38:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:24 INFO - 'PWD': '/builds/slave/test', 09:38:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:24 INFO - 'SHELL': '/bin/bash', 09:38:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.amvnHJBo8U/Listeners', 09:38:24 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 09:38:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:24 INFO - 'USER': 'cltbld', 09:38:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:24 INFO - 'XPC_FLAGS': '0x0', 09:38:24 INFO - 'XPC_SERVICE_NAME': '0', 09:38:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:38:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:38:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:38:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:38:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:38:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:38:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:38:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:38:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:38:24 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:38:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:38:24 INFO - Cleaning up... 09:38:24 INFO - Return code: 0 09:38:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:38:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:38:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:38:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:38:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:38:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:38:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1015a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10163d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8eb1cc56c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10150cd30>, '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 0x101271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101504b20>, '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.ggboIhxFml/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.amvnHJBo8U/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:38:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:38:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:38:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggboIhxFml/Render', 09:38:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:38:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:38:24 INFO - 'HOME': '/Users/cltbld', 09:38:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:38:24 INFO - 'LOGNAME': 'cltbld', 09:38:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:38:24 INFO - 'MOZ_NO_REMOTE': '1', 09:38:24 INFO - 'NO_EM_RESTART': '1', 09:38:24 INFO - 'PAGER': '/bin/cat', 09:38:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:38:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:38:24 INFO - 'PWD': '/builds/slave/test', 09:38:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:38:24 INFO - 'SHELL': '/bin/bash', 09:38:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.amvnHJBo8U/Listeners', 09:38:24 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 09:38:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:38:24 INFO - 'USER': 'cltbld', 09:38:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:38:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:38:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:38:24 INFO - 'XPC_FLAGS': '0x0', 09:38:24 INFO - 'XPC_SERVICE_NAME': '0', 09:38:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:38:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:38:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:38:24 INFO - Cleaning up... 09:38:24 INFO - Return code: 0 09:38:24 INFO - Running post-action listener: _resource_record_post_action 09:38:24 INFO - Running post-action listener: _start_resource_monitoring 09:38:24 INFO - Starting resource monitoring. 09:38:24 INFO - ##### 09:38:24 INFO - ##### Finished create-virtualenv step (success) 09:38:24 INFO - ##### 09:38:24 INFO - ##### 09:38:24 INFO - ##### Running install step. 09:38:24 INFO - ##### 09:38:24 INFO - Running pre-action listener: _resource_record_pre_action 09:38:24 INFO - Running main action method: install 09:38:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:38:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:38:24 INFO - Reading from file tmpfile_stdout 09:38:24 INFO - Detecting whether we're running mozinstall >=1.0... 09:38:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:38:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:38:25 INFO - Reading from file tmpfile_stdout 09:38:25 INFO - Output received: 09:38:25 INFO - Usage: mozinstall [options] installer 09:38:25 INFO - Options: 09:38:25 INFO - -h, --help show this help message and exit 09:38:25 INFO - -d DEST, --destination=DEST 09:38:25 INFO - Directory to install application into. [default: 09:38:25 INFO - "/builds/slave/test"] 09:38:25 INFO - --app=APP Application being installed. [default: firefox] 09:38:25 INFO - mkdir: /builds/slave/test/build/application 09:38:25 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'] 09:38:25 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 09:38:59 INFO - Reading from file tmpfile_stdout 09:38:59 INFO - Output received: 09:38:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:38:59 INFO - Running post-action listener: _resource_record_post_action 09:38:59 INFO - ##### 09:38:59 INFO - ##### Finished install step (success) 09:38:59 INFO - ##### 09:38:59 INFO - ##### 09:38:59 INFO - ##### Running run-tests step. 09:38:59 INFO - ##### 09:38:59 INFO - Running pre-action listener: _resource_record_pre_action 09:38:59 INFO - Running main action method: run_tests 09:38:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:38:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:38:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:38:59 INFO - 2016-07-05 09:38:59.917 screenresolution[1643:8658] starting screenresolution argv=screenresolution get 09:38:59 INFO - 2016-07-05 09:38:59.920 screenresolution[1643:8658] Display 0: 1600x1200x32@60 09:38:59 INFO - 2016-07-05 09:38:59.926 screenresolution[1644:8661] starting screenresolution argv=screenresolution list 09:38:59 INFO - Available Modes on Display 0 09:38:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:38:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:38:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:38:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:39:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:39:00 INFO - Intel Iris: 09:39:00 INFO - Chipset Model: Intel Iris 09:39:00 INFO - Type: GPU 09:39:00 INFO - Bus: Built-In 09:39:00 INFO - VRAM (Dynamic, Max): 1536 MB 09:39:00 INFO - Vendor: Intel (0x8086) 09:39:00 INFO - Device ID: 0x0a2e 09:39:00 INFO - Revision ID: 0x0009 09:39:00 INFO - Displays: 09:39:00 INFO - Display: 09:39:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:39:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:39:00 INFO - Display Serial Number: 1600x1200 60 09:39:00 INFO - Main Display: Yes 09:39:00 INFO - Mirror: Off 09:39:00 INFO - Online: Yes 09:39:00 INFO - Rotation: Supported 09:39:00 INFO - Return code: 0 09:39:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:39:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:39:00 INFO - Python 2.7.3 09:39:00 INFO - Return code: 0 09:39:00 INFO - grabbing minidump binary from tooltool 09:39: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')]} 09:39:00 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 0x10150cd30>, '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 0x101271db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101504b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:39:00 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:39:00 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:39:00 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:39:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:39:00 INFO - Return code: 0 09:39:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:39:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:39:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:39:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:39:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:39:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:39:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0016 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:39:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ggboIhxFml/Render', 09:39:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:39:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:39:00 INFO - 'HOME': '/Users/cltbld', 09:39:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:39:00 INFO - 'LOGNAME': 'cltbld', 09:39:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:39:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:39:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:39:00 INFO - 'MOZ_NO_REMOTE': '1', 09:39:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:39:00 INFO - 'NO_EM_RESTART': '1', 09:39:00 INFO - 'PAGER': '/bin/cat', 09:39:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:39:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:39:00 INFO - 'PWD': '/builds/slave/test', 09:39:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:39:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:39:00 INFO - 'SHELL': '/bin/bash', 09:39:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.amvnHJBo8U/Listeners', 09:39:00 INFO - 'TMPDIR': '/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/', 09:39:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:39:00 INFO - 'USER': 'cltbld', 09:39:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:39:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:39:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:39:00 INFO - 'XPC_FLAGS': '0x0', 09:39:00 INFO - 'XPC_SERVICE_NAME': '0', 09:39:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:39:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0016', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:39:00 INFO - mozversion application_buildid: 20160705070325 09:39:00 INFO - mozversion application_changeset: b2e48cc9d3a02fc172910ce8b29f83c19db20777 09:39:00 INFO - mozversion application_display_name: Nightly 09:39:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:39:00 INFO - mozversion application_name: Firefox 09:39:00 INFO - mozversion application_remotingname: firefox 09:39:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:39:00 INFO - mozversion application_vendor: Mozilla 09:39:00 INFO - mozversion application_version: 50.0a1 09:39:00 INFO - mozversion platform_buildid: 20160705070325 09:39:00 INFO - mozversion platform_changeset: b2e48cc9d3a02fc172910ce8b29f83c19db20777 09:39:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:39:00 INFO - mozversion platform_version: 50.0a1 09:39:00 INFO - using testdate: 1467736740 09:39:00 INFO - actual date: 1467736740 09:39:00 INFO - starting webserver on 'localhost:49270' 09:39:00 INFO - SUITE-START | Running 2 tests 09:39:00 INFO - TEST-START | dromaeo_css 09:39:00 INFO - operating with platform_type : mac_ 09:39:00 INFO - Initialising browser for dromaeo_css test... 09:39:00 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpBDFHbR/profile http://localhost:49270/getInfo.html) 09:39:02 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 09:39:02 INFO - PROCESS | 1654 | colorDepth:24 09:39:02 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 09:39:02 INFO - PROCESS | 1654 | __metrics 09:39:03 INFO - PROCESS | 1654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:39:03 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:39:03 INFO - TEST-INFO | 1654: exit 0 09:39:03 INFO - Browser initialized. 09:39:03 INFO - Running cycle 1/1 for dromaeo_css test... 09:39:03 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpBDFHbR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:39:15 INFO - PROCESS | 1657 | RSS: Main: 245035008 09:39:15 INFO - PROCESS | 1657 | 09:40:06 INFO - PROCESS | 1657 | 1467736806849 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:42:17 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 09:42:18 INFO - PROCESS | 1657 | RSS: Main: 349143040 09:42:18 INFO - PROCESS | 1657 | 09:45:21 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 09:45:22 INFO - PROCESS | 1657 | RSS: Main: 382255104 09:45:22 INFO - PROCESS | 1657 | 09:48:24 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 09:48:25 INFO - PROCESS | 1657 | RSS: Main: 374620160 09:48:25 INFO - PROCESS | 1657 | 09:51:27 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 09:51:28 INFO - PROCESS | 1657 | RSS: Main: 372412416 09:51:28 INFO - PROCESS | 1657 | 09:54:31 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 09:54:31 INFO - PROCESS | 1657 | RSS: Main: 340156416 09:54:31 INFO - PROCESS | 1657 | 09:57:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 09:57:35 INFO - PROCESS | 1657 | RSS: Main: 438108160 09:57:35 INFO - PROCESS | 1657 | 09:57:35 INFO - PROCESS | 1657 | __start_tp_report 09:57:35 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 09:57:35 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 09:57:35 INFO - PROCESS | 1657 | |i|pagename|runs| 09:57:35 INFO - PROCESS | 1657 | |0;dojo.html;3347.41420251456;3358.865645374185;3370.629230784614;3388.6611338866132;3428.965710342896;5663.9433605663935;5725.513331366828;5737.684427356494;5814.2441482607255;5869.941300586993;5726;5768.855778605537;5792.131180322943;5799.449052340032;5814.273215848019;5187.481251874801;5234.790608375661;5238.423773384925;5244.160934250521;5264.210368444745;5218.947810521903;5270.81552145675;5283.260343551887;5299.947000529984;5327.813526526573;5284.365876094855;5295.179247216687;5300.681959082467;5334.466553344673;5338.039152952467;858.1048414197094;5240.056789777838;5278.181881808326;5299.788008479656;5321.840344789663;3261.9972494993704;5135.845924622267;5150.4334523202415;5221.347331583552;5252.658577192508;5693.886122277573;5731;5819.098039803837;5820.621659592113;5838.532917366643;6276.748930042792;6281.497480201619;6287.779927702509;6292.307846136921;6306.621602703853;4547.522510136454;6164.87670246597;6194.287656919467;6196.535259855529;6240.063990401431;5818.5636077294375;5845.649261044351;5859.707014649208;5907.4683278505145;5924.407559244128;6275.717592708339;6283.214598175228;6287.874242515124;6289.496840252768;6291.653959032297;6065.423784740443;6092.5430592705725;6114.023656277693;6117.847053823601;6193.473554747897;6176.166217560572;6239.719212635442;6295.905561416583;6299.307076221612;6306.747730090837;6096.1336269316525;6242.15730876335;6265.561410701207;6266.436020758154;6295.653739044396;6157.107219453154;6250.218722659668;6255.405736455121;6285.8742825143245;6299.055141728777;4915.90168196634;4917.7786999585105;4918;4926.630502712239;4966.602671786249;4320.395144679747;4380.496242932039;4409.933850992237;4414.028913638995;4446.933296000562;4825.445073816556;5729.627574207663;5805.970970145122;5825;5878.882422351529;6190.938090619126;6216.222972128484;6225.937740622536;6250.15622890913;6252.218472690914;5782.10377391505;5804.245448091721;5837.445442682938;5841.766329346863;5848.5321174305955;5323.813666521653;5380.811671591554;5395.05586522357;5418.458154184629;5438.347398312228;6268.592541484789;6276.215473065867;6282.120503129474;6301.936980630318;6310.873782524323;6157.89157951573;6199.5660303780105;6251.249850018027;6267.435930766253;6288.622682639057;6185.226846644169;6203.596766210182;6234.532410069172;6259.3427690091885;6324.936750632435;6220.595661282002;6233.096201050913;6245.594036387802;6253.249610046823;6254.9687251563455;5965.970170149121;6176;6271.905921411274;6275.6862156892885;6280.905786413299;6103.115048318058;6165.599236049642;6175.3824617539685;6179.474744646755;6180.6291622502795;6137.202163718688;6154.261488621215;6159.81520554384;6167.599106058271;6197.008478643395;5788.21859049023;5906.527477801851;6004.339522652417;6005.729742161526;6036.978729532748;6104.732011601694;6144.924638188389;6145.692715364304;6153.476954458921;6164.445199932027;5989.580729349078;5995.760169593342;6000;6007.459328660441;6034.758609655564 09:57:35 INFO - PROCESS | 1657 | |1;ext.html;479.64985560540805;541.6885290957699;543.0415316965554;552.5772783820381;553.167482938178;30042.399152016947;31891.52162717561;32079.83960080199;42645;43652.47216347428;45345.54654453455;45448.318275225814;45514.544854551445;45636.54363456364;45696.086078278415;9766.951165244163;9939.55272012761;9941.055599718014;9948.850767238497;9949.452780097092;8728.214460698537;8921.063288354726;9653.855192172123;11986;12223.572174973877;12003.399830008508;12068.818967715491;12072.973797227247;12104.757904841897;12106.213096148766;12077.15459917806;12151.453184606715;12155.027597792156;12177.695557611041;12180.451879665436;8690.261327787146;11522.76954460906;11763.764724705543;11784.469698863571;12101;43294.78352608248;43502.56497435017;43601.34597981033;43666;43713.34429983553;3495.0912762681633;3534;3835.252125835463;4021.698372622065;4248.426462427564;31941.680583194102;33559.32881342359;33686.82096126624;33738.662613373795;33824.49263261053;2839.872205750746;3356.8083330417594;3394.439917413624;3450.9827450862585;3487;565538;565914;566599.1670041665;567821.1608941929;568176.1591192017;64230;64429.67785161044;64465.67767161134;64576;64665.67667161634;66805.66597166983;66942;67154;67190.66404668044;67240.32759672438;16943.15284235783;17182.312707491812;17182.828171718123;17222.224999875034;17255.827441725673;11256.493457794419;11855.703607409883;13121.934390327988;13136.934315328363;13170.143940643828;14940.626484337761;16752.162391880356;17198.484045478657;17202.31190752356;17722.02528860899;5776.653400795882;6596.60420374779;6686.699098540577;7215.881538361562;7434.1822399536;920.995395023034;950.3585080071063;952.7522844060594;1006.8993100689872;1062.9096526795154;27132;30918.53622195669;31287.389985356444;31483.685163148075;31789.52315715266;1028.8250997330472;1081.9080378167787;1095.0418383914075;1098.708842156813;1101.840392830046;15569.065856048674;16168.676626467404;16230;16268.75596866048;17087.401940932006;676130;693266;696367;698307;698913;53550;53866.73066634642;62936.37063629305;63406.36593634097;63521.68239158775;1310.5544115000946;1426.7503186942245;1438.2305466575333;1458.577012666336;1459.306829256095;31963;32400.675993240708;32517.585653506987;32861.67138328586;33583;53758.65603359947;54343.45656543384;54815.45184548103;55169.44830551643;55287;650983;651017;652501;653492;658169;42319;42666.78666606647;42936;43189.78405107954;43361.7831910851;50428;50828.745856270485;51870.74064629653;52088.739556301975;52391.21413178729;40933;41173.588264118174;41175.97060073522;41224;41816.79091604644;39321.820345389686;39857.80071099742;41171.7941410291;42695.71912842619;42836;37313.813430933755;40184.799076005605;40866.79566602148;40876.79561602173;41119.7944010278;34136.48795268023;35549.82225088858;36483.90548283556;36490.45264320984;36585.15855835206;20587.69118463194;26189.214323570322;26326;26671.86664066667;26937.73062269352 09:57:35 INFO - PROCESS | 1657 | |2;jquery.html;363.79991004947306;390.1494741463609;392.3232424068484;393.58476806968645;416.8520175337755;14546.199959002251;16307.266173022213;21361.359159225227;21673.024713887873;21977.120915163374;16889.83110168898;21674.566508669817;22303.107875684953;22429.32712018642;22576.887115564397;5252.317198764155;5868.119782032697;6119.847003824895;6132.325444201134;6149.508039356863;6055.7274922628585;6080.787172448965;6089.725962331706;6104.664243466607;6110.3889611038985;5872.442117998805;5938.8218353449465;6024.548158888079;6045.425684559959;6047.546434017444;5762.250907382014;6007.098935159717;6035.094735789623;6037.154798328215;6050.334463209044;5960.195373624592;5980.700964951735;6004.489618382443;6005.60963537373;6018.699065046774;13090.672733181651;13862.514811981633;13870.791938120936;13931;13945.721085578332;2231.5760005599045;2273.6475846243857;2302.6200676888293;2380.1312520929737;2443.4624382635793;9686.75783105428;10087.747806304827;10237.641682541076;10294.330868493524;10402.947985260025;1681.2963210503565;1756.9648607027916;1846.3537761783402;1848.4639454558162;1868.8037756035556;80909.99999999942;125396.37301813524;128577;134330;140886.59113408838;11358.716032099264;11408.087353011739;11715.297082175097;11813.527458901548;11942.522299107939;14621.634459138393;14743.705125897422;14755.631109222355;14764.852351476347;14788.704225915422;7620.657070431844;8001.639926203218;8005.9599702001115;8133.389995750224;8553.144685531397;7553.8866916996285;7919.84160316802;8498.66005359791;8702.39083264178;8879;4520.050789334211;4606.193916064675;4632.3977882875015;4691.038337140946;4704.529547045268;1852.2776117314258;1893.5928775313098;1897.2790339670837;1903.5526651236949;1905.7522522072043;672.9569167789906;732.9120505539263;828.3952714518315;900.3742399032609;984.5126662302063;18297.908510457364;21358.466038348855;21802.236921707663;22042.44893877634;22341.664875026887;932.4498545857977;944.1596978688972;951.2199996003211;962.1052421248166;1037.8858325584179;11320;11469.885301146882;11704;11708;11717.589884354007;619878.7018194731;629469;634541;637555;639397;123882;125784.11323830149;144873;145820.4565473796;150645;989.4656885282011;996.9750756231152;1044.4151275285865;1054.2567489919588;1070.1118072000222;18278.725819112457;19230.90328780613;19912.86570600641;19954;19962.90018549898;42322.576774231864;56360.71819640876;57518.99999999833;57675.711621443304;58295;64259;79456;86706;87099;88046.55976720076;8128;8339.54132522717;8596.183362580545;8604.956975215084;8686.131386861516;12671.30307833078;13351.532696355582;13458.461661533429;13594.592162235149;13720.86279137196;11584.884151158718;11626.651200463999;11681;11692.123090768056;11874.762504749684;11534.826977595176;11546.711332216762;11608.535658573563;11631.6510504685;11671.533138674698;11580;11816.527338906348;11879.168458207843;11891.405429728651;11944.104192185447;8321.708740194064;8325.750227493185;8344.874826877724;8387.790305242417;8400.075991641037;11114.44427778624;11606.94196529012;11799.764004719686;11860.406979651156;11931.880681193425 09:57:35 INFO - PROCESS | 1657 | |3;mootools.html;1572.0174890693313;1600.503843808419;1842.4288470574122;1935.2839449403705;1977.8022197780224;8550.52972086535;8798.296136309096;8975.461472311667;8984.371094023421;8993.775155621104;7791.610419479018;8069.314108300787;8076.676932922691;8170.9591452042805;8795.95602021989;2176.6517357222847;2315.768423157679;2391.222852572912;2392.9760702392928;2398.95202095958;2356.1988923765916;2362.917297894574;2382.03527571333;2387.4270175157926;2390.677258570097;2088.41524373175;2373.1693907132453;2383.4279772854566;2384.76152384761;2385.71371435428;2305.446692793726;2374.8456350337347;2377.4888398994212;2377.869217193054;2381.071382160959;2343.3438637181616;2352.8235382346393;2353.7528559501347;2363.1610778173845;2378.179528062833;8862.734117976472;8916.55417229136;8938.195562399414;8943.329250306188;8998.415103018348;4235.597618226264;5329.120695085307;5398.1902714593125;5413.675179489243;5423.945760542423;3543.875964341236;3628.963710362889;3687.3915803892323;3697.149655579205;3700.481932529448;1071.1805468816362;1101.9944900275527;1121.4336759936261;1122.5565901468874;1132.5016992523263;11590;11612.709682257926;11660.533578656843;11667.299962002222;11692.883071169264;4509.278515437514;4714.45783734868;4801.4718380978065;4828.95171048292;4831.299461578052;9911.306208565475;9929.304948653526;9939.055789699967;9950.950245248727;9962.501874906226;5345.625806193529;5348.171033489815;5348.572114230852;5356.196570514376;5360.195970604363;4431.977840110843;4713.3872596562915;4906.190478570996;4913.606911447076;4914.385701787277;3434.639362866913;3483.7806767631632;3778.7354885158325;3863.285292220948;3878.108035151931;1100.5487750022612;1187.4775098956432;1189.1854079955258;1197.7724232395817;1202.0864143251192;873.4104479476455;952.4142652268805;956.8708224389758;959.460066593108;1022.9795404091876;5966.701664916737;5973.551983601247;5997.370276120944;6025.969870150709;6109.725062372204;929.9163075323254;948.5684013773716;981.3817295104182;986.9407835529892;997.3218211616171;3594.029612004744;3986.80065996699;4071.877843664695;4135.255653982252;4158.792060396968;10006.44964526958;10642.04221620058;10747.731306717395;10800.891991079989;10806.351618902734;10613.893861061291;10684.679459616224;10717.732056698489;10765.03114719679;10773.299735517166;8201.13888041759;8245.216704413071;8265.95867020661;8273.958630206811;8304;5478.890422191614;5930.52555795544;6038.396160383926;6056.848578785565;6060.090986352083;9656.32405731592;9774.266929980138;10060.144887684628;10070.597176112873;10070.748231294276;10153.492325383553;10208.2343824212;10254.436006019441;10264.486775661337;10287.43419111956;6926.792196234121;7561.168271490242;7685.308322251024;7697.923020769945;7706.075270967295;7035.648217589202;7946.523208607502;8441.366897482836;8535.615897284513;8569.357298202534;6807.183138023469;7455.956166136853;7607.961960190164;7620.733274335372;7739.18738532446;7491.625418729151;7498.32515073646;7531.209223031722;7638.083429988214;7643.770686879402;6040.426159514839;7019.543729657556;7151.60666163366;7163.10461192351;7164.570125792469;5072.695638261716;5114.15616423286;5154.458781827854;5175.275461435327;5244.711540865289;5119.0273847968765;5142.434332223527;5205.58355331565;5217.81737639196;5333.893322133613 09:57:35 INFO - PROCESS | 1657 | |4;prototype.html;302.3574903330423;309.44918045878336;318.43636762929617;319.6483867745479;321.30437602590393;4470.575295346945;5381.784728610855;5388.332775276243;5393.973030134854;5447.373552041517;4456.710313829599;5031.547160755531;5148.510891465314;5325.680459172452;5410.215518749779;4080.571539988303;4124.803806896004;4128.566500517432;4132.318167502359;4164.646005089571;4135.441715368432;4137.634580588414;4138.544760076389;4142.958570414303;4144.9585504145025;3696.482492451059;3869.7097717671145;4073.4500842386196;4090.427340172378;4108.322126849066;3413.9658603413895;4028.4561584186054;4085.5301640311445;4086.3257562502154;4095.5699651536456;2992.925176870596;3562.5368702068563;3600.6039335673054;3601.225736466672;3611.295797319524;4082.6121518455698;4835.323054772335;5250.606204534675;5305.65513241638;5307.45242283706;4647.00178923063;4693.389859318301;4709.387779588666;4713.316569097496;4752.691075080109;4673.742944138071;5618.494335509824;5642.4075472075665;5660.462256085665;5677.120046392774;3724.9519493785856;3895.61043895611;3956.6834653227675;4002.7198096133284;4035.5762644922447;5208;5879.735411906475;5913.467787899067;5920.4967577755515;5936.940630593682;3997.960020399817;4004.2592120457807;4025.8590949317218;4029.899252518652;4050.4531888195306;3734.9066273343383;3872.9349428907276;4097.242010228058;4123.463949686521;4130.359794231899;4337.566243375701;4641.234196357631;4654.232051711429;4670.859874203779;4688.929666055012;5051.661309752918;5623;5739.913901291484;5749.712514374264;5824.941750582525;1093.956241750337;1238.5107882386408;1277.9297138657464;1295.1063766001616;1364.9317534123254;796.9878254616721;856.6873091321747;860.3289434241302;906.8413027720122;916.1434059154578;5288.550473209821;5319.946800532022;5337.759800808973;5341.973290133525;5569.554435645138;5755.152551301907;5757.913631295534;5801.7099145042575;5825.533957283407;5870.26621672291;4203.684723645739;5285.55072818807;5298.788048478018;5317.946820531823;5328.92006619901;2546.668933038693;2592.6240695099104;2976.627921509811;3057.2573633029406;3067.217859445827;5148.639595228373;5670.376258611549;5865.064528705517;5944.613600115978;5975.940240597625;5267.920981185285;5656.095024796013;5872.559558033165;5887.0580707086665;5926.555508336892;4782.378290822171;4984.925226121611;5016.739119572308;5020.648554601216;5994.610350327215;4861.690696919077;4917.459079501324;5519.944800551943;5572.4706152915405;5710.771569137188;4961.627877909244;5627.831165065055;5675.432456754292;5683.2895888014;5714.314282285986;5014.272930424995;5020.598352131894;5642.548596112383;5941.37615550361;6015.909761353496;3140.2149462634343;3873.86441474547;3885.9805700971315;3889.8444062237195;3896.181801821649;4991.905018888312;5663.915041274302;5740.68426236561;5786.4213578643485;5789.305283365855;4766.451858036232;5227.321333538996;5653;5654.116400635623;5665.631733937281;4781.952180478151;5266.315379000705;5515.917261241165;5524.502794748492;5555;4545.704529205591;5259.368875734936;5423.3491980962535;5477.589180811376;5594.692291923983;5224.947750522446;5680.914786278292;5690.544756419559;5690.687012214368;5708.828735137952;4453.799579019017;4711.552402521755;5522.392536820867;5565.1374037024325;5573.665580065209 09:57:35 INFO - PROCESS | 1657 | |5;yui.html;755.4711701808728;756.9167391586925;768.1588660416845;781.3592853859834;887.2591386192529;14880.702385952289;14961.92519037406;15281.083135011893;15300.846991530081;15357.616059598502;15054;15255.38978440861;15260.389584416638;15286.464973725922;15290.92963492556;708.9361957423831;733.7431898835418;881.7575166829125;896.4800415758845;911.8085202107533;916.4226537281537;922.4511415707677;923.353652443289;924.1359329027347;926.7451450851003;969.4280374578997;973.2846357926918;974.28390133252;974.7611835100411;978.0708327089258;744.8733715268418;925.255169588481;956.4357029352645;959.1080295325345;968.0803236924914;706.7950294414613;707.2114592229678;707.8655055539431;708.0052526200697;709.283623540223;15276.694466110726;15298.617534561614;15300.234988250542;15301.693966120727;15338.003029803027;14256.857431425657;14281.714365712627;14302.64243393913;14381.712365752732;14495.637609059753;11961.700957476045;12645.747085058247;13188.6702832429;13227.470901163942;13535.255560944242;4231.640310573574;4469.128519938613;4526.298423744325;4763.65514911879;4786.952130478686;120831;121110;121344;121446;121580.1762973556;11018.669439916815;11473;11754.70613234676;11924;12232.204906681038;23071.884640577024;23434.296971090895;23854.522909541713;23898.761012389652;23953.401164970666;7561.7731468056045;8309.418340716093;8650.307975361957;8745.300375970035;8754.21212090915;4551.635869130461;4603.424571928509;4649.354920691915;4732.029933863619;4808.543188397097;3587.8026708531283;3714.8699795507027;3724.776513409204;3725.832337544817;3727.496787933594;694.7047504810537;694.746417557598;696.3941371007155;697.7174244430912;699.3111784891878;585.3034888482692;600.4535872356134;608.057510858168;710.2152121905324;714.43559587926;8549.444286121383;8835.64657413709;9071.909280907239;9104.544772761335;9229.492377919145;600.4955837096772;650.2099948562519;654.201873714068;683.8632273545211;694.2190036209265;4383.978080109579;4415.42009753374;4544.455105141132;4565.155446242456;4616.815327386867;143950;144160;145461;152833;156795;114796.42601786938;116038.41980790212;116575;124075;131622;74478.6276068627;105172.47413763189;113649;115948.42025789816;122781;8774.166454186841;8969.37214394986;9375.531223438664;9410.29422793307;9522.190613797904;54068.91862162813;55149.72425137849;55805.72097139488;56455.71772141277;57658.423415765305;49648.751756240985;74561.25438745761;80283;81048.59475702584;82754;11412.372319522174;13459.865401345862;14285.785713214518;14328.856711432752;14385;14266.572002839932;14594.27028648542;14611.41554337857;14612.342444590231;14622.488212912496;11771.705707357385;13040;13253.602391928258;13370.331483425984;13401.195928244337;12746.171498852545;12917.031222658527;13376.464941402235;13542.390592423168;13611.455541778218;12970.027247956252;13042.673933151747;13253.271070091238;13463.05758596928;13717.17696938187;10899.065950744865;11184.049355804847;11216.495257713586;11483.081353491867;11555.364454954723;9962.850557241793;10245.903687306041;10253.436061016715;10862.782744345226;11384.886151138382 09:57:35 INFO - PROCESS | 1657 | __end_tp_report 09:57:35 INFO - PROCESS | 1657 | __start_cc_report 09:57:35 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1090 09:57:35 INFO - PROCESS | 1657 | __end_cc_report 09:57:35 INFO - PROCESS | 1657 | __startTimestamp1467737855458__endTimestamp 09:57:35 INFO - PROCESS | 1657 | 09:57:35 INFO - PROCESS | 1657 | ------- Summary: start ------- 09:57:35 INFO - PROCESS | 1657 | Number of tests: 6 09:57:35 INFO - PROCESS | 1657 | 09:57:35 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5724.36 Median:6005.73 stddev:772.41 (12.9%) stddev-sans-first:751.94 09:57:35 INFO - PROCESS | 1657 | Values: 3347.4 3358.9 3370.6 3388.7 3429.0 5663.9 5725.5 5737.7 5814.2 5869.9 5726.0 5768.9 5792.1 5799.4 5814.3 5187.5 5234.8 5238.4 5244.2 5264.2 5218.9 5270.8 5283.3 5299.9 5327.8 5284.4 5295.2 5300.7 5334.5 5338.0 858.1 5240.1 5278.2 5299.8 5321.8 3262.0 5135.8 5150.4 5221.3 5252.7 5693.9 5731.0 5819.1 5820.6 5838.5 6276.7 6281.5 6287.8 6292.3 6306.6 4547.5 6164.9 6194.3 6196.5 6240.1 5818.6 5845.6 5859.7 5907.5 5924.4 6275.7 6283.2 6287.9 6289.5 6291.7 6065.4 6092.5 6114.0 6117.8 6193.5 6176.2 6239.7 6295.9 6299.3 6306.7 6096.1 6242.2 6265.6 6266.4 6295.7 6157.1 6250.2 6255.4 6285.9 6299.1 4915.9 4917.8 4918.0 4926.6 4966.6 4320.4 4380.5 4409.9 4414.0 4446.9 4825.4 5729.6 5806.0 5825.0 5878.9 6190.9 6216.2 6225.9 6250.2 6252.2 5782.1 5804.2 5837.4 5841.8 5848.5 5323.8 5380.8 5395.1 5418.5 5438.3 6268.6 6276.2 6282.1 6301.9 6310.9 6157.9 6199.6 6251.2 6267.4 6288.6 6185.2 6203.6 6234.5 6259.3 6324.9 6220.6 6233.1 6245.6 6253.2 6255.0 5966.0 6176.0 6271.9 6275.7 6280.9 6103.1 6165.6 6175.4 6179.5 6180.6 6137.2 6154.3 6159.8 6167.6 6197.0 5788.2 5906.5 6004.3 6005.7 6037.0 6104.7 6144.9 6145.7 6153.5 6164.4 5989.6 5995.8 6000.0 6007.5 6034.8 09:57:35 INFO - PROCESS | 1657 | 09:57:35 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:77619.43 Median:40904.90 stddev:171064.86 (418.2%) stddev-sans-first:171446.70 09:57:35 INFO - PROCESS | 1657 | Values: 479.6 541.7 543.0 552.6 553.2 30042.4 31891.5 32079.8 42645.0 43652.5 45345.5 45448.3 45514.5 45636.5 45696.1 9767.0 9939.6 9941.1 9948.9 9949.5 8728.2 8921.1 9653.9 11986.0 12223.6 12003.4 12068.8 12073.0 12104.8 12106.2 12077.2 12151.5 12155.0 12177.7 12180.5 8690.3 11522.8 11763.8 11784.5 12101.0 43294.8 43502.6 43601.3 43666.0 43713.3 3495.1 3534.0 3835.3 4021.7 4248.4 31941.7 33559.3 33686.8 33738.7 33824.5 2839.9 3356.8 3394.4 3451.0 3487.0 565538.0 565914.0 566599.2 567821.2 568176.2 64230.0 64429.7 64465.7 64576.0 64665.7 66805.7 66942.0 67154.0 67190.7 67240.3 16943.2 17182.3 17182.8 17222.2 17255.8 11256.5 11855.7 13121.9 13136.9 13170.1 14940.6 16752.2 17198.5 17202.3 17722.0 5776.7 6596.6 6686.7 7215.9 7434.2 921.0 950.4 952.8 1006.9 1062.9 27132.0 30918.5 31287.4 31483.7 31789.5 1028.8 1081.9 1095.0 1098.7 1101.8 15569.1 16168.7 16230.0 16268.8 17087.4 676130.0 693266.0 696367.0 698307.0 698913.0 53550.0 53866.7 62936.4 63406.4 63521.7 1310.6 1426.8 1438.2 1458.6 1459.3 31963.0 32400.7 32517.6 32861.7 33583.0 53758.7 54343.5 54815.5 55169.4 55287.0 650983.0 651017.0 652501.0 653492.0 658169.0 42319.0 42666.8 42936.0 43189.8 43361.8 50428.0 50828.7 51870.7 52088.7 52391.2 40933.0 41173.6 41176.0 41224.0 41816.8 39321.8 39857.8 41171.8 42695.7 42836.0 37313.8 40184.8 40866.8 40876.8 41119.8 34136.5 35549.8 36483.9 36490.5 36585.2 20587.7 26189.2 26326.0 26671.9 26937.7 09:57:35 INFO - PROCESS | 1657 | 09:57:35 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:36330.99 Median:22385.50 stddev:105709.44 (472.2%) stddev-sans-first:105971.48 09:57:35 INFO - PROCESS | 1657 | Values: 363.8 390.1 392.3 393.6 416.9 14546.2 16307.3 21361.4 21673.0 21977.1 16889.8 21674.6 22303.1 22429.3 22576.9 5252.3 5868.1 6119.8 6132.3 6149.5 6055.7 6080.8 6089.7 6104.7 6110.4 5872.4 5938.8 6024.5 6045.4 6047.5 5762.3 6007.1 6035.1 6037.2 6050.3 5960.2 5980.7 6004.5 6005.6 6018.7 13090.7 13862.5 13870.8 13931.0 13945.7 2231.6 2273.6 2302.6 2380.1 2443.5 9686.8 10087.7 10237.6 10294.3 10402.9 1681.3 1757.0 1846.4 1848.5 1868.8 80910.0 125396.4 128577.0 134330.0 140886.6 11358.7 11408.1 11715.3 11813.5 11942.5 14621.6 14743.7 14755.6 14764.9 14788.7 7620.7 8001.6 8006.0 8133.4 8553.1 7553.9 7919.8 8498.7 8702.4 8879.0 4520.1 4606.2 4632.4 4691.0 4704.5 1852.3 1893.6 1897.3 1903.6 1905.8 673.0 732.9 828.4 900.4 984.5 18297.9 21358.5 21802.2 22042.4 22341.7 932.4 944.2 951.2 962.1 1037.9 11320.0 11469.9 11704.0 11708.0 11717.6 619878.7 629469.0 634541.0 637555.0 639397.0 123882.0 125784.1 144873.0 145820.5 150645.0 989.5 997.0 1044.4 1054.3 1070.1 18278.7 19230.9 19912.9 19954.0 19962.9 42322.6 56360.7 57519.0 57675.7 58295.0 64259.0 79456.0 86706.0 87099.0 88046.6 8128.0 8339.5 8596.2 8605.0 8686.1 12671.3 13351.5 13458.5 13594.6 13720.9 11584.9 11626.7 11681.0 11692.1 11874.8 11534.8 11546.7 11608.5 11631.7 11671.5 11580.0 11816.5 11879.2 11891.4 11944.1 8321.7 8325.8 8344.9 8387.8 8400.1 11114.4 11606.9 11799.8 11860.4 11931.9 09:57:35 INFO - PROCESS | 1657 | 09:57:35 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5727.99 Median:5093.43 stddev:3190.36 (62.6%) stddev-sans-first:3184.03 09:57:35 INFO - PROCESS | 1657 | Values: 1572.0 1600.5 1842.4 1935.3 1977.8 8550.5 8798.3 8975.5 8984.4 8993.8 7791.6 8069.3 8076.7 8171.0 8796.0 2176.7 2315.8 2391.2 2393.0 2399.0 2356.2 2362.9 2382.0 2387.4 2390.7 2088.4 2373.2 2383.4 2384.8 2385.7 2305.4 2374.8 2377.5 2377.9 2381.1 2343.3 2352.8 2353.8 2363.2 2378.2 8862.7 8916.6 8938.2 8943.3 8998.4 4235.6 5329.1 5398.2 5413.7 5423.9 3543.9 3629.0 3687.4 3697.1 3700.5 1071.2 1102.0 1121.4 1122.6 1132.5 11590.0 11612.7 11660.5 11667.3 11692.9 4509.3 4714.5 4801.5 4829.0 4831.3 9911.3 9929.3 9939.1 9951.0 9962.5 5345.6 5348.2 5348.6 5356.2 5360.2 4432.0 4713.4 4906.2 4913.6 4914.4 3434.6 3483.8 3778.7 3863.3 3878.1 1100.5 1187.5 1189.2 1197.8 1202.1 873.4 952.4 956.9 959.5 1023.0 5966.7 5973.6 5997.4 6026.0 6109.7 929.9 948.6 981.4 986.9 997.3 3594.0 3986.8 4071.9 4135.3 4158.8 10006.4 10642.0 10747.7 10800.9 10806.4 10613.9 10684.7 10717.7 10765.0 10773.3 8201.1 8245.2 8266.0 8274.0 8304.0 5478.9 5930.5 6038.4 6056.8 6060.1 9656.3 9774.3 10060.1 10070.6 10070.7 10153.5 10208.2 10254.4 10264.5 10287.4 6926.8 7561.2 7685.3 7697.9 7706.1 7035.6 7946.5 8441.4 8535.6 8569.4 6807.2 7456.0 7608.0 7620.7 7739.2 7491.6 7498.3 7531.2 7638.1 7643.8 6040.4 7019.5 7151.6 7163.1 7164.6 5072.7 5114.2 5154.5 5175.3 5244.7 5119.0 5142.4 5205.6 5217.8 5333.9 09:57:35 INFO - PROCESS | 1657 | 09:57:35 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4543.35 Median:5026.10 stddev:1386.15 (27.6%) stddev-sans-first:1353.00 09:57:35 INFO - PROCESS | 1657 | Values: 302.4 309.4 318.4 319.6 321.3 4470.6 5381.8 5388.3 5394.0 5447.4 4456.7 5031.5 5148.5 5325.7 5410.2 4080.6 4124.8 4128.6 4132.3 4164.6 4135.4 4137.6 4138.5 4143.0 4145.0 3696.5 3869.7 4073.5 4090.4 4108.3 3414.0 4028.5 4085.5 4086.3 4095.6 2992.9 3562.5 3600.6 3601.2 3611.3 4082.6 4835.3 5250.6 5305.7 5307.5 4647.0 4693.4 4709.4 4713.3 4752.7 4673.7 5618.5 5642.4 5660.5 5677.1 3725.0 3895.6 3956.7 4002.7 4035.6 5208.0 5879.7 5913.5 5920.5 5936.9 3998.0 4004.3 4025.9 4029.9 4050.5 3734.9 3872.9 4097.2 4123.5 4130.4 4337.6 4641.2 4654.2 4670.9 4688.9 5051.7 5623.0 5739.9 5749.7 5824.9 1094.0 1238.5 1277.9 1295.1 1364.9 797.0 856.7 860.3 906.8 916.1 5288.6 5319.9 5337.8 5342.0 5569.6 5755.2 5757.9 5801.7 5825.5 5870.3 4203.7 5285.6 5298.8 5317.9 5328.9 2546.7 2592.6 2976.6 3057.3 3067.2 5148.6 5670.4 5865.1 5944.6 5975.9 5267.9 5656.1 5872.6 5887.1 5926.6 4782.4 4984.9 5016.7 5020.6 5994.6 4861.7 4917.5 5519.9 5572.5 5710.8 4961.6 5627.8 5675.4 5683.3 5714.3 5014.3 5020.6 5642.5 5941.4 6015.9 3140.2 3873.9 3886.0 3889.8 3896.2 4991.9 5663.9 5740.7 5786.4 5789.3 4766.5 5227.3 5653.0 5654.1 5665.6 4782.0 5266.3 5515.9 5524.5 5555.0 4545.7 5259.4 5423.3 5477.6 5594.7 5224.9 5680.9 5690.5 5690.7 5708.8 4453.8 4711.6 5522.4 5565.1 5573.7 09:57:35 INFO - PROCESS | 1657 | 09:57:35 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:24311.98 Median:3725.30 stddev:38722.79 (1039.5%) stddev-sans-first:38791.02 09:57:35 INFO - PROCESS | 1657 | Values: 755.5 756.9 768.2 781.4 887.3 14880.7 14961.9 15281.1 15300.8 15357.6 15054.0 15255.4 15260.4 15286.5 15290.9 708.9 733.7 881.8 896.5 911.8 916.4 922.5 923.4 924.1 926.7 969.4 973.3 974.3 974.8 978.1 744.9 925.3 956.4 959.1 968.1 706.8 707.2 707.9 708.0 709.3 15276.7 15298.6 15300.2 15301.7 15338.0 14256.9 14281.7 14302.6 14381.7 14495.6 11961.7 12645.7 13188.7 13227.5 13535.3 4231.6 4469.1 4526.3 4763.7 4787.0 120831.0 121110.0 121344.0 121446.0 121580.2 11018.7 11473.0 11754.7 11924.0 12232.2 23071.9 23434.3 23854.5 23898.8 23953.4 7561.8 8309.4 8650.3 8745.3 8754.2 4551.6 4603.4 4649.4 4732.0 4808.5 3587.8 3714.9 3724.8 3725.8 3727.5 694.7 694.7 696.4 697.7 699.3 585.3 600.5 608.1 710.2 714.4 8549.4 8835.6 9071.9 9104.5 9229.5 600.5 650.2 654.2 683.9 694.2 4384.0 4415.4 4544.5 4565.2 4616.8 143950.0 144160.0 145461.0 152833.0 156795.0 114796.4 116038.4 116575.0 124075.0 131622.0 74478.6 105172.5 113649.0 115948.4 122781.0 8774.2 8969.4 9375.5 9410.3 9522.2 54068.9 55149.7 55805.7 56455.7 57658.4 49648.8 74561.3 80283.0 81048.6 82754.0 11412.4 13459.9 14285.8 14328.9 14385.0 14266.6 14594.3 14611.4 14612.3 14622.5 11771.7 13040.0 13253.6 13370.3 13401.2 12746.2 12917.0 13376.5 13542.4 13611.5 12970.0 13042.7 13253.3 13463.1 13717.2 10899.1 11184.0 11216.5 11483.1 11555.4 9962.9 10245.9 10253.4 10862.8 11384.9 09:57:35 INFO - PROCESS | 1657 | -------- Summary: end -------- 09:57:35 INFO - PROCESS | 1657 | 09:57:36 INFO - TEST-INFO | 1657: exit 0 09:57:36 INFO - TEST-OK | dromaeo_css | took 1115532ms 09:57:36 INFO - TEST-START | kraken 09:57:36 INFO - operating with platform_type : mac_ 09:57:36 INFO - Initialising browser for kraken test... 09:57:36 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpsC37Ls/profile http://localhost:49270/getInfo.html) 09:57:38 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 09:57:38 INFO - PROCESS | 1662 | colorDepth:24 09:57:38 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 09:57:38 INFO - PROCESS | 1662 | __metrics 09:57:38 INFO - PROCESS | 1662 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:57:38 INFO - PROCESS | 1662 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:57:39 INFO - TEST-INFO | 1662: exit 0 09:57:39 INFO - Browser initialized. 09:57:39 INFO - Running cycle 1/1 for kraken test... 09:57:39 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/T/tmpsC37Ls/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:50 INFO - PROCESS | 1663 | RSS: Main: 243687424 09:57:50 INFO - PROCESS | 1663 | 09:57:53 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 09:57:54 INFO - PROCESS | 1663 | RSS: Main: 294424576 09:57:54 INFO - PROCESS | 1663 | 09:57:57 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 09:57:57 INFO - PROCESS | 1663 | RSS: Main: 304267264 09:57:57 INFO - PROCESS | 1663 | 09:58:01 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 09:58:01 INFO - PROCESS | 1663 | RSS: Main: 388689920 09:58:01 INFO - PROCESS | 1663 | 09:58:03 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 09:58:04 INFO - PROCESS | 1663 | RSS: Main: 291602432 09:58:04 INFO - PROCESS | 1663 | 09:58:06 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:58:07 INFO - PROCESS | 1663 | RSS: Main: 294879232 09:58:07 INFO - PROCESS | 1663 | 09:58:10 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 09:58:10 INFO - PROCESS | 1663 | RSS: Main: 332443648 09:58:10 INFO - PROCESS | 1663 | 09:58:13 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 09:58:14 INFO - PROCESS | 1663 | RSS: Main: 335122432 09:58:14 INFO - PROCESS | 1663 | 09:58:16 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 09:58:17 INFO - PROCESS | 1663 | RSS: Main: 330817536 09:58:17 INFO - PROCESS | 1663 | 09:58:19 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:58:19 INFO - PROCESS | 1663 | RSS: Main: 311398400 09:58:19 INFO - PROCESS | 1663 | 09:58:21 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:58:22 INFO - PROCESS | 1663 | RSS: Main: 318525440 09:58:22 INFO - PROCESS | 1663 | 09:58:24 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:58:25 INFO - PROCESS | 1663 | RSS: Main: 315908096 09:58:25 INFO - PROCESS | 1663 | 09:58:28 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:58:28 INFO - PROCESS | 1663 | RSS: Main: 325406720 09:58:28 INFO - PROCESS | 1663 | 09:58:31 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:58:32 INFO - PROCESS | 1663 | RSS: Main: 327454720 09:58:32 INFO - PROCESS | 1663 | 09:58:34 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 09:58:34 INFO - PROCESS | 1663 | RSS: Main: 371105792 09:58:34 INFO - PROCESS | 1663 | 09:58:34 INFO - PROCESS | 1663 | __start_tp_report 09:58:34 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 09:58:34 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 09:58:34 INFO - PROCESS | 1663 | |i|pagename|runs| 09:58:34 INFO - PROCESS | 1663 | |0;ai-astar;100;113;114;112;111;113;113;113;112;113 09:58:34 INFO - PROCESS | 1663 | |1;audio-beat-detection;143;106;107;157;106;106;131;122;139;105 09:58:34 INFO - PROCESS | 1663 | |2;audio-dft;180;184;227;260;191;186;181;183;181;185 09:58:34 INFO - PROCESS | 1663 | |3;audio-fft;78;72;57;107;55;56;62;58;126;60 09:58:34 INFO - PROCESS | 1663 | |4;audio-oscillator;82;85;94;71;60;66;109;98;96;118 09:58:34 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;89;87;132;88;89;84;87;86;88;85 09:58:34 INFO - PROCESS | 1663 | |6;imaging-darkroom;95;95;96;95;95;94;97;98;95;94 09:58:34 INFO - PROCESS | 1663 | |7;imaging-desaturate;85;87;86;83;85;83;86;83;84;85 09:58:34 INFO - PROCESS | 1663 | |8;json-parse-financial;55;50;54;54;53;53;53;52;52;53 09:58:34 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;71;72;77;86;105;101;83;70;70;70 09:58:34 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;94;91;100;77;81;80;80;82;84;91 09:58:34 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;138;138;138;134;134;135;138;134;226;136 09:58:34 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;159;160;171;219;191;162;158;162;159;161 09:58:34 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;65;66;66;65;66;65;66;65;66;67 09:58:34 INFO - PROCESS | 1663 | __end_tp_report 09:58:34 INFO - PROCESS | 1663 | __start_cc_report 09:58:34 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,2089 09:58:34 INFO - PROCESS | 1663 | __end_cc_report 09:58:34 INFO - PROCESS | 1663 | __startTimestamp1467737914348__endTimestamp 09:58:34 INFO - PROCESS | 1663 | 09:58:34 INFO - PROCESS | 1663 | ------- Summary: start ------- 09:58:34 INFO - PROCESS | 1663 | Number of tests: 14 09:58:34 INFO - PROCESS | 1663 | 09:58:34 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:111.40 Median:113.00 stddev:4.09 (3.6%) stddev-sans-first:0.87 09:58:34 INFO - PROCESS | 1663 | Values: 100.0 113.0 114.0 112.0 111.0 113.0 113.0 113.0 112.0 113.0 09:58:34 INFO - PROCESS | 1663 | 09:58:34 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:122.20 Median:126.50 stddev:19.20 (15.2%) stddev-sans-first:18.83 09:58:34 INFO - PROCESS | 1663 | Values: 143.0 106.0 107.0 157.0 106.0 106.0 131.0 122.0 139.0 105.0 09:58:34 INFO - PROCESS | 1663 | 09:58:34 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:195.80 Median:185.50 stddev:26.50 (14.3%) stddev-sans-first:27.49 09:58:34 INFO - PROCESS | 1663 | Values: 180.0 184.0 227.0 260.0 191.0 186.0 181.0 183.0 181.0 185.0 09:58:34 INFO - PROCESS | 1663 | 09:58:34 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:73.10 Median:59.00 stddev:24.44 (41.4%) stddev-sans-first:25.86 09:58:34 INFO - PROCESS | 1663 | Values: 78.0 72.0 57.0 107.0 55.0 56.0 62.0 58.0 126.0 60.0 09:58:34 INFO - PROCESS | 1663 | 09:58:34 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:87.90 Median:83.50 stddev:18.69 (22.4%) stddev-sans-first:19.70 09:58:34 INFO - PROCESS | 1663 | Values: 82.0 85.0 94.0 71.0 60.0 66.0 109.0 98.0 96.0 118.0 09:58:34 INFO - PROCESS | 1663 | 09:58:34 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:91.50 Median:87.50 stddev:14.32 (16.4%) stddev-sans-first:15.16 09:58:34 INFO - PROCESS | 1663 | Values: 89.0 87.0 132.0 88.0 89.0 84.0 87.0 86.0 88.0 85.0 09:58:34 INFO - PROCESS | 1663 | 09:58:34 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:95.40 Median:95.00 stddev:1.26 (1.3%) stddev-sans-first:1.33 09:58:34 INFO - PROCESS | 1663 | Values: 95.0 95.0 96.0 95.0 95.0 94.0 97.0 98.0 95.0 94.0 09:58:34 INFO - PROCESS | 1663 | 09:58:34 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:1.42 (1.7%) stddev-sans-first:1.50 09:58:34 INFO - PROCESS | 1663 | Values: 85.0 87.0 86.0 83.0 85.0 83.0 86.0 83.0 84.0 85.0 09:58:34 INFO - PROCESS | 1663 | 09:58:34 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:52.90 Median:53.00 stddev:1.37 (2.6%) stddev-sans-first:1.22 09:58:34 INFO - PROCESS | 1663 | Values: 55.0 50.0 54.0 54.0 53.0 53.0 53.0 52.0 52.0 53.0 09:58:34 INFO - PROCESS | 1663 | 09:58:34 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:80.50 Median:71.50 stddev:13.18 (18.4%) stddev-sans-first:13.52 09:58:34 INFO - PROCESS | 1663 | Values: 71.0 72.0 77.0 86.0 105.0 101.0 83.0 70.0 70.0 70.0 09:58:34 INFO - PROCESS | 1663 | 09:58:34 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:83.00 stddev:7.51 (9.1%) stddev-sans-first:7.39 09:58:34 INFO - PROCESS | 1663 | Values: 94.0 91.0 100.0 77.0 81.0 80.0 80.0 82.0 84.0 91.0 09:58:34 INFO - PROCESS | 1663 | 09:58:34 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:145.10 Median:138.00 stddev:28.48 (20.6%) stddev-sans-first:30.09 09:58:34 INFO - PROCESS | 1663 | Values: 138.0 138.0 138.0 134.0 134.0 135.0 138.0 134.0 226.0 136.0 09:58:34 INFO - PROCESS | 1663 | 09:58:34 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.20 Median:162.00 stddev:19.83 (12.2%) stddev-sans-first:20.61 09:58:34 INFO - PROCESS | 1663 | Values: 159.0 160.0 171.0 219.0 191.0 162.0 158.0 162.0 159.0 161.0 09:58:34 INFO - PROCESS | 1663 | 09:58:34 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 09:58:34 INFO - PROCESS | 1663 | Values: 65.0 66.0 66.0 65.0 66.0 65.0 66.0 65.0 66.0 67.0 09:58:34 INFO - PROCESS | 1663 | -------- Summary: end -------- 09:58:34 INFO - PROCESS | 1663 | 09:58:35 INFO - TEST-INFO | 1663: exit 0 09:58:35 INFO - TEST-OK | kraken | took 59134ms 09:58:35 INFO - SUITE-END | took 1174s 09:58:35 INFO - Completed test suite (00:19:34) 09:58:35 INFO - javascript benchmark 09:58:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3347.41420251456, 3358.865645374185, 3370.629230784614, 3388.6611338866132, 3428.965710342896, 5663.9433605663935, 5725.513331366828, 5737.684427356494, 5814.2441482607255, 5869.941300586993, 5726.0, 5768.855778605537, 5792.131180322943, 5799.449052340032, 5814.273215848019, 5187.481251874801, 5234.790608375661, 5238.423773384925, 5244.160934250521, 5264.210368444745, 5218.947810521903, 5270.81552145675, 5283.260343551887, 5299.947000529984, 5327.813526526573, 5284.365876094855, 5295.179247216687, 5300.681959082467, 5334.466553344673, 5338.039152952467, 858.1048414197094, 5240.056789777838, 5278.181881808326, 5299.788008479656, 5321.840344789663, 3261.9972494993704, 5135.845924622267, 5150.4334523202415, 5221.347331583552, 5252.658577192508, 5693.886122277573, 5731.0, 5819.098039803837, 5820.621659592113, 5838.532917366643, 6276.748930042792, 6281.497480201619, 6287.779927702509, 6292.307846136921, 6306.621602703853, 4547.522510136454, 6164.87670246597, 6194.287656919467, 6196.535259855529, 6240.063990401431, 5818.5636077294375, 5845.649261044351, 5859.707014649208, 5907.4683278505145, 5924.407559244128, 6275.717592708339, 6283.214598175228, 6287.874242515124, 6289.496840252768, 6291.653959032297, 6065.423784740443, 6092.5430592705725, 6114.023656277693, 6117.847053823601, 6193.473554747897, 6176.166217560572, 6239.719212635442, 6295.905561416583, 6299.307076221612, 6306.747730090837, 6096.1336269316525, 6242.15730876335, 6265.561410701207, 6266.436020758154, 6295.653739044396, 6157.107219453154, 6250.218722659668, 6255.405736455121, 6285.8742825143245, 6299.055141728777, 4915.90168196634, 4917.7786999585105, 4918.0, 4926.630502712239, 4966.602671786249, 4320.395144679747, 4380.496242932039, 4409.933850992237, 4414.028913638995, 4446.933296000562, 4825.445073816556, 5729.627574207663, 5805.970970145122, 5825.0, 5878.882422351529, 6190.938090619126, 6216.222972128484, 6225.937740622536, 6250.15622890913, 6252.218472690914, 5782.10377391505, 5804.245448091721, 5837.445442682938, 5841.766329346863, 5848.5321174305955, 5323.813666521653, 5380.811671591554, 5395.05586522357, 5418.458154184629, 5438.347398312228, 6268.592541484789, 6276.215473065867, 6282.120503129474, 6301.936980630318, 6310.873782524323, 6157.89157951573, 6199.5660303780105, 6251.249850018027, 6267.435930766253, 6288.622682639057, 6185.226846644169, 6203.596766210182, 6234.532410069172, 6259.3427690091885, 6324.936750632435, 6220.595661282002, 6233.096201050913, 6245.594036387802, 6253.249610046823, 6254.9687251563455, 5965.970170149121, 6176.0, 6271.905921411274, 6275.6862156892885, 6280.905786413299, 6103.115048318058, 6165.599236049642, 6175.3824617539685, 6179.474744646755, 6180.6291622502795, 6137.202163718688, 6154.261488621215, 6159.81520554384, 6167.599106058271, 6197.008478643395, 5788.21859049023, 5906.527477801851, 6004.339522652417, 6005.729742161526, 6036.978729532748, 6104.732011601694, 6144.924638188389, 6145.692715364304, 6153.476954458921, 6164.445199932027, 5989.580729349078, 5995.760169593342, 6000.0, 6007.459328660441, 6034.758609655564], "value": 5676.840358383376, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.64985560540805, 541.6885290957699, 543.0415316965554, 552.5772783820381, 553.167482938178, 30042.399152016947, 31891.52162717561, 32079.83960080199, 42645.0, 43652.47216347428, 45345.54654453455, 45448.318275225814, 45514.544854551445, 45636.54363456364, 45696.086078278415, 9766.951165244163, 9939.55272012761, 9941.055599718014, 9948.850767238497, 9949.452780097092, 8728.214460698537, 8921.063288354726, 9653.855192172123, 11986.0, 12223.572174973877, 12003.399830008508, 12068.818967715491, 12072.973797227247, 12104.757904841897, 12106.213096148766, 12077.15459917806, 12151.453184606715, 12155.027597792156, 12177.695557611041, 12180.451879665436, 8690.261327787146, 11522.76954460906, 11763.764724705543, 11784.469698863571, 12101.0, 43294.78352608248, 43502.56497435017, 43601.34597981033, 43666.0, 43713.34429983553, 3495.0912762681633, 3534.0, 3835.252125835463, 4021.698372622065, 4248.426462427564, 31941.680583194102, 33559.32881342359, 33686.82096126624, 33738.662613373795, 33824.49263261053, 2839.872205750746, 3356.8083330417594, 3394.439917413624, 3450.9827450862585, 3487.0, 565538.0, 565914.0, 566599.1670041665, 567821.1608941929, 568176.1591192017, 64230.0, 64429.67785161044, 64465.67767161134, 64576.0, 64665.67667161634, 66805.66597166983, 66942.0, 67154.0, 67190.66404668044, 67240.32759672438, 16943.15284235783, 17182.312707491812, 17182.828171718123, 17222.224999875034, 17255.827441725673, 11256.493457794419, 11855.703607409883, 13121.934390327988, 13136.934315328363, 13170.143940643828, 14940.626484337761, 16752.162391880356, 17198.484045478657, 17202.31190752356, 17722.02528860899, 5776.653400795882, 6596.60420374779, 6686.699098540577, 7215.881538361562, 7434.1822399536, 920.995395023034, 950.3585080071063, 952.7522844060594, 1006.8993100689872, 1062.9096526795154, 27132.0, 30918.53622195669, 31287.389985356444, 31483.685163148075, 31789.52315715266, 1028.8250997330472, 1081.9080378167787, 1095.0418383914075, 1098.708842156813, 1101.840392830046, 15569.065856048674, 16168.676626467404, 16230.0, 16268.75596866048, 17087.401940932006, 676130.0, 693266.0, 696367.0, 698307.0, 698913.0, 53550.0, 53866.73066634642, 62936.37063629305, 63406.36593634097, 63521.68239158775, 1310.5544115000946, 1426.7503186942245, 1438.2305466575333, 1458.577012666336, 1459.306829256095, 31963.0, 32400.675993240708, 32517.585653506987, 32861.67138328586, 33583.0, 53758.65603359947, 54343.45656543384, 54815.45184548103, 55169.44830551643, 55287.0, 650983.0, 651017.0, 652501.0, 653492.0, 658169.0, 42319.0, 42666.78666606647, 42936.0, 43189.78405107954, 43361.7831910851, 50428.0, 50828.745856270485, 51870.74064629653, 52088.739556301975, 52391.21413178729, 40933.0, 41173.588264118174, 41175.97060073522, 41224.0, 41816.79091604644, 39321.820345389686, 39857.80071099742, 41171.7941410291, 42695.71912842619, 42836.0, 37313.813430933755, 40184.799076005605, 40866.79566602148, 40876.79561602173, 41119.7944010278, 34136.48795268023, 35549.82225088858, 36483.90548283556, 36490.45264320984, 36585.15855835206, 20587.69118463194, 26189.214323570322, 26326.0, 26671.86664066667, 26937.73062269352], "value": 21402.181291253415, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.79991004947306, 390.1494741463609, 392.3232424068484, 393.58476806968645, 416.8520175337755, 14546.199959002251, 16307.266173022213, 21361.359159225227, 21673.024713887873, 21977.120915163374, 16889.83110168898, 21674.566508669817, 22303.107875684953, 22429.32712018642, 22576.887115564397, 5252.317198764155, 5868.119782032697, 6119.847003824895, 6132.325444201134, 6149.508039356863, 6055.7274922628585, 6080.787172448965, 6089.725962331706, 6104.664243466607, 6110.3889611038985, 5872.442117998805, 5938.8218353449465, 6024.548158888079, 6045.425684559959, 6047.546434017444, 5762.250907382014, 6007.098935159717, 6035.094735789623, 6037.154798328215, 6050.334463209044, 5960.195373624592, 5980.700964951735, 6004.489618382443, 6005.60963537373, 6018.699065046774, 13090.672733181651, 13862.514811981633, 13870.791938120936, 13931.0, 13945.721085578332, 2231.5760005599045, 2273.6475846243857, 2302.6200676888293, 2380.1312520929737, 2443.4624382635793, 9686.75783105428, 10087.747806304827, 10237.641682541076, 10294.330868493524, 10402.947985260025, 1681.2963210503565, 1756.9648607027916, 1846.3537761783402, 1848.4639454558162, 1868.8037756035556, 80909.99999999942, 125396.37301813524, 128577.0, 134330.0, 140886.59113408838, 11358.716032099264, 11408.087353011739, 11715.297082175097, 11813.527458901548, 11942.522299107939, 14621.634459138393, 14743.705125897422, 14755.631109222355, 14764.852351476347, 14788.704225915422, 7620.657070431844, 8001.639926203218, 8005.9599702001115, 8133.389995750224, 8553.144685531397, 7553.8866916996285, 7919.84160316802, 8498.66005359791, 8702.39083264178, 8879.0, 4520.050789334211, 4606.193916064675, 4632.3977882875015, 4691.038337140946, 4704.529547045268, 1852.2776117314258, 1893.5928775313098, 1897.2790339670837, 1903.5526651236949, 1905.7522522072043, 672.9569167789906, 732.9120505539263, 828.3952714518315, 900.3742399032609, 984.5126662302063, 18297.908510457364, 21358.466038348855, 21802.236921707663, 22042.44893877634, 22341.664875026887, 932.4498545857977, 944.1596978688972, 951.2199996003211, 962.1052421248166, 1037.8858325584179, 11320.0, 11469.885301146882, 11704.0, 11708.0, 11717.589884354007, 619878.7018194731, 629469.0, 634541.0, 637555.0, 639397.0, 123882.0, 125784.11323830149, 144873.0, 145820.4565473796, 150645.0, 989.4656885282011, 996.9750756231152, 1044.4151275285865, 1054.2567489919588, 1070.1118072000222, 18278.725819112457, 19230.90328780613, 19912.86570600641, 19954.0, 19962.90018549898, 42322.576774231864, 56360.71819640876, 57518.99999999833, 57675.711621443304, 58295.0, 64259.0, 79456.0, 86706.0, 87099.0, 88046.55976720076, 8128.0, 8339.54132522717, 8596.183362580545, 8604.956975215084, 8686.131386861516, 12671.30307833078, 13351.532696355582, 13458.461661533429, 13594.592162235149, 13720.86279137196, 11584.884151158718, 11626.651200463999, 11681.0, 11692.123090768056, 11874.762504749684, 11534.826977595176, 11546.711332216762, 11608.535658573563, 11631.6510504685, 11671.533138674698, 11580.0, 11816.527338906348, 11879.168458207843, 11891.405429728651, 11944.104192185447, 8321.708740194064, 8325.750227493185, 8344.874826877724, 8387.790305242417, 8400.075991641037, 11114.44427778624, 11606.94196529012, 11799.764004719686, 11860.406979651156, 11931.880681193425], "value": 9606.852751974178, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1572.0174890693313, 1600.503843808419, 1842.4288470574122, 1935.2839449403705, 1977.8022197780224, 8550.52972086535, 8798.296136309096, 8975.461472311667, 8984.371094023421, 8993.775155621104, 7791.610419479018, 8069.314108300787, 8076.676932922691, 8170.9591452042805, 8795.95602021989, 2176.6517357222847, 2315.768423157679, 2391.222852572912, 2392.9760702392928, 2398.95202095958, 2356.1988923765916, 2362.917297894574, 2382.03527571333, 2387.4270175157926, 2390.677258570097, 2088.41524373175, 2373.1693907132453, 2383.4279772854566, 2384.76152384761, 2385.71371435428, 2305.446692793726, 2374.8456350337347, 2377.4888398994212, 2377.869217193054, 2381.071382160959, 2343.3438637181616, 2352.8235382346393, 2353.7528559501347, 2363.1610778173845, 2378.179528062833, 8862.734117976472, 8916.55417229136, 8938.195562399414, 8943.329250306188, 8998.415103018348, 4235.597618226264, 5329.120695085307, 5398.1902714593125, 5413.675179489243, 5423.945760542423, 3543.875964341236, 3628.963710362889, 3687.3915803892323, 3697.149655579205, 3700.481932529448, 1071.1805468816362, 1101.9944900275527, 1121.4336759936261, 1122.5565901468874, 1132.5016992523263, 11590.0, 11612.709682257926, 11660.533578656843, 11667.299962002222, 11692.883071169264, 4509.278515437514, 4714.45783734868, 4801.4718380978065, 4828.95171048292, 4831.299461578052, 9911.306208565475, 9929.304948653526, 9939.055789699967, 9950.950245248727, 9962.501874906226, 5345.625806193529, 5348.171033489815, 5348.572114230852, 5356.196570514376, 5360.195970604363, 4431.977840110843, 4713.3872596562915, 4906.190478570996, 4913.606911447076, 4914.385701787277, 3434.639362866913, 3483.7806767631632, 3778.7354885158325, 3863.285292220948, 3878.108035151931, 1100.5487750022612, 1187.4775098956432, 1189.1854079955258, 1197.7724232395817, 1202.0864143251192, 873.4104479476455, 952.4142652268805, 956.8708224389758, 959.460066593108, 1022.9795404091876, 5966.701664916737, 5973.551983601247, 5997.370276120944, 6025.969870150709, 6109.725062372204, 929.9163075323254, 948.5684013773716, 981.3817295104182, 986.9407835529892, 997.3218211616171, 3594.029612004744, 3986.80065996699, 4071.877843664695, 4135.255653982252, 4158.792060396968, 10006.44964526958, 10642.04221620058, 10747.731306717395, 10800.891991079989, 10806.351618902734, 10613.893861061291, 10684.679459616224, 10717.732056698489, 10765.03114719679, 10773.299735517166, 8201.13888041759, 8245.216704413071, 8265.95867020661, 8273.958630206811, 8304.0, 5478.890422191614, 5930.52555795544, 6038.396160383926, 6056.848578785565, 6060.090986352083, 9656.32405731592, 9774.266929980138, 10060.144887684628, 10070.597176112873, 10070.748231294276, 10153.492325383553, 10208.2343824212, 10254.436006019441, 10264.486775661337, 10287.43419111956, 6926.792196234121, 7561.168271490242, 7685.308322251024, 7697.923020769945, 7706.075270967295, 7035.648217589202, 7946.523208607502, 8441.366897482836, 8535.615897284513, 8569.357298202534, 6807.183138023469, 7455.956166136853, 7607.961960190164, 7620.733274335372, 7739.18738532446, 7491.625418729151, 7498.32515073646, 7531.209223031722, 7638.083429988214, 7643.770686879402, 6040.426159514839, 7019.543729657556, 7151.60666163366, 7163.10461192351, 7164.570125792469, 5072.695638261716, 5114.15616423286, 5154.458781827854, 5175.275461435327, 5244.711540865289, 5119.0273847968765, 5142.434332223527, 5205.58355331565, 5217.81737639196, 5333.893322133613], "value": 4618.908725571839, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.3574903330423, 309.44918045878336, 318.43636762929617, 319.6483867745479, 321.30437602590393, 4470.575295346945, 5381.784728610855, 5388.332775276243, 5393.973030134854, 5447.373552041517, 4456.710313829599, 5031.547160755531, 5148.510891465314, 5325.680459172452, 5410.215518749779, 4080.571539988303, 4124.803806896004, 4128.566500517432, 4132.318167502359, 4164.646005089571, 4135.441715368432, 4137.634580588414, 4138.544760076389, 4142.958570414303, 4144.9585504145025, 3696.482492451059, 3869.7097717671145, 4073.4500842386196, 4090.427340172378, 4108.322126849066, 3413.9658603413895, 4028.4561584186054, 4085.5301640311445, 4086.3257562502154, 4095.5699651536456, 2992.925176870596, 3562.5368702068563, 3600.6039335673054, 3601.225736466672, 3611.295797319524, 4082.6121518455698, 4835.323054772335, 5250.606204534675, 5305.65513241638, 5307.45242283706, 4647.00178923063, 4693.389859318301, 4709.387779588666, 4713.316569097496, 4752.691075080109, 4673.742944138071, 5618.494335509824, 5642.4075472075665, 5660.462256085665, 5677.120046392774, 3724.9519493785856, 3895.61043895611, 3956.6834653227675, 4002.7198096133284, 4035.5762644922447, 5208.0, 5879.735411906475, 5913.467787899067, 5920.4967577755515, 5936.940630593682, 3997.960020399817, 4004.2592120457807, 4025.8590949317218, 4029.899252518652, 4050.4531888195306, 3734.9066273343383, 3872.9349428907276, 4097.242010228058, 4123.463949686521, 4130.359794231899, 4337.566243375701, 4641.234196357631, 4654.232051711429, 4670.859874203779, 4688.929666055012, 5051.661309752918, 5623.0, 5739.913901291484, 5749.712514374264, 5824.941750582525, 1093.956241750337, 1238.5107882386408, 1277.9297138657464, 1295.1063766001616, 1364.9317534123254, 796.9878254616721, 856.6873091321747, 860.3289434241302, 906.8413027720122, 916.1434059154578, 5288.550473209821, 5319.946800532022, 5337.759800808973, 5341.973290133525, 5569.554435645138, 5755.152551301907, 5757.913631295534, 5801.7099145042575, 5825.533957283407, 5870.26621672291, 4203.684723645739, 5285.55072818807, 5298.788048478018, 5317.946820531823, 5328.92006619901, 2546.668933038693, 2592.6240695099104, 2976.627921509811, 3057.2573633029406, 3067.217859445827, 5148.639595228373, 5670.376258611549, 5865.064528705517, 5944.613600115978, 5975.940240597625, 5267.920981185285, 5656.095024796013, 5872.559558033165, 5887.0580707086665, 5926.555508336892, 4782.378290822171, 4984.925226121611, 5016.739119572308, 5020.648554601216, 5994.610350327215, 4861.690696919077, 4917.459079501324, 5519.944800551943, 5572.4706152915405, 5710.771569137188, 4961.627877909244, 5627.831165065055, 5675.432456754292, 5683.2895888014, 5714.314282285986, 5014.272930424995, 5020.598352131894, 5642.548596112383, 5941.37615550361, 6015.909761353496, 3140.2149462634343, 3873.86441474547, 3885.9805700971315, 3889.8444062237195, 3896.181801821649, 4991.905018888312, 5663.915041274302, 5740.68426236561, 5786.4213578643485, 5789.305283365855, 4766.451858036232, 5227.321333538996, 5653.0, 5654.116400635623, 5665.631733937281, 4781.952180478151, 5266.315379000705, 5515.917261241165, 5524.502794748492, 5555.0, 4545.704529205591, 5259.368875734936, 5423.3491980962535, 5477.589180811376, 5594.692291923983, 5224.947750522446, 5680.914786278292, 5690.544756419559, 5690.687012214368, 5708.828735137952, 4453.799579019017, 4711.552402521755, 5522.392536820867, 5565.1374037024325, 5573.665580065209], "value": 4098.745993363772, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.4711701808728, 756.9167391586925, 768.1588660416845, 781.3592853859834, 887.2591386192529, 14880.702385952289, 14961.92519037406, 15281.083135011893, 15300.846991530081, 15357.616059598502, 15054.0, 15255.38978440861, 15260.389584416638, 15286.464973725922, 15290.92963492556, 708.9361957423831, 733.7431898835418, 881.7575166829125, 896.4800415758845, 911.8085202107533, 916.4226537281537, 922.4511415707677, 923.353652443289, 924.1359329027347, 926.7451450851003, 969.4280374578997, 973.2846357926918, 974.28390133252, 974.7611835100411, 978.0708327089258, 744.8733715268418, 925.255169588481, 956.4357029352645, 959.1080295325345, 968.0803236924914, 706.7950294414613, 707.2114592229678, 707.8655055539431, 708.0052526200697, 709.283623540223, 15276.694466110726, 15298.617534561614, 15300.234988250542, 15301.693966120727, 15338.003029803027, 14256.857431425657, 14281.714365712627, 14302.64243393913, 14381.712365752732, 14495.637609059753, 11961.700957476045, 12645.747085058247, 13188.6702832429, 13227.470901163942, 13535.255560944242, 4231.640310573574, 4469.128519938613, 4526.298423744325, 4763.65514911879, 4786.952130478686, 120831.0, 121110.0, 121344.0, 121446.0, 121580.1762973556, 11018.669439916815, 11473.0, 11754.70613234676, 11924.0, 12232.204906681038, 23071.884640577024, 23434.296971090895, 23854.522909541713, 23898.761012389652, 23953.401164970666, 7561.7731468056045, 8309.418340716093, 8650.307975361957, 8745.300375970035, 8754.21212090915, 4551.635869130461, 4603.424571928509, 4649.354920691915, 4732.029933863619, 4808.543188397097, 3587.8026708531283, 3714.8699795507027, 3724.776513409204, 3725.832337544817, 3727.496787933594, 694.7047504810537, 694.746417557598, 696.3941371007155, 697.7174244430912, 699.3111784891878, 585.3034888482692, 600.4535872356134, 608.057510858168, 710.2152121905324, 714.43559587926, 8549.444286121383, 8835.64657413709, 9071.909280907239, 9104.544772761335, 9229.492377919145, 600.4955837096772, 650.2099948562519, 654.201873714068, 683.8632273545211, 694.2190036209265, 4383.978080109579, 4415.42009753374, 4544.455105141132, 4565.155446242456, 4616.815327386867, 143950.0, 144160.0, 145461.0, 152833.0, 156795.0, 114796.42601786938, 116038.41980790212, 116575.0, 124075.0, 131622.0, 74478.6276068627, 105172.47413763189, 113649.0, 115948.42025789816, 122781.0, 8774.166454186841, 8969.37214394986, 9375.531223438664, 9410.29422793307, 9522.190613797904, 54068.91862162813, 55149.72425137849, 55805.72097139488, 56455.71772141277, 57658.423415765305, 49648.751756240985, 74561.25438745761, 80283.0, 81048.59475702584, 82754.0, 11412.372319522174, 13459.865401345862, 14285.785713214518, 14328.856711432752, 14385.0, 14266.572002839932, 14594.27028648542, 14611.41554337857, 14612.342444590231, 14622.488212912496, 11771.705707357385, 13040.0, 13253.602391928258, 13370.331483425984, 13401.195928244337, 12746.171498852545, 12917.031222658527, 13376.464941402235, 13542.390592423168, 13611.455541778218, 12970.027247956252, 13042.673933151747, 13253.271070091238, 13463.05758596928, 13717.17696938187, 10899.065950744865, 11184.049355804847, 11216.495257713586, 11483.081353491867, 11555.364454954723, 9962.850557241793, 10245.903687306041, 10253.436061016715, 10862.782744345226, 11384.886151138382], "value": 7945.529517641048, "unit": "score"}], "name": "dromaeo_css", "value": 7483.152278604974, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 113.0, 114.0, 112.0, 111.0, 113.0, 113.0, 113.0, 112.0, 113.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 106.0, 107.0, 157.0, 106.0, 106.0, 131.0, 122.0, 139.0, 105.0], "value": 122.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 184.0, 227.0, 260.0, 191.0, 186.0, 181.0, 183.0, 181.0, 185.0], "value": 195.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 72.0, 57.0, 107.0, 55.0, 56.0, 62.0, 58.0, 126.0, 60.0], "value": 73.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 94.0, 71.0, 60.0, 66.0, 109.0, 98.0, 96.0, 118.0], "value": 87.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 132.0, 88.0, 89.0, 84.0, 87.0, 86.0, 88.0, 85.0], "value": 91.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 94.0, 97.0, 98.0, 95.0, 94.0], "value": 95.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 86.0, 83.0, 85.0, 83.0, 86.0, 83.0, 84.0, 85.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 50.0, 54.0, 54.0, 53.0, 53.0, 53.0, 52.0, 52.0, 53.0], "value": 52.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 77.0, 86.0, 105.0, 101.0, 83.0, 70.0, 70.0, 70.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 91.0, 100.0, 77.0, 81.0, 80.0, 80.0, 82.0, 84.0, 91.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 138.0, 134.0, 134.0, 135.0, 138.0, 134.0, 226.0, 136.0], "value": 145.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 171.0, 219.0, 191.0, 162.0, 158.0, 162.0, 159.0, 161.0], "value": 170.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 65.0, 66.0, 65.0, 66.0, 65.0, 66.0, 67.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1462.4, "alertThreshold": 2.0}]} 09:58:35 INFO - Return code: 0 09:58:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:58:35 INFO - # TBPL SUCCESS # 09:58:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:58:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:58:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:58:35 INFO - 2016-07-05 09:58:35.651 screenresolution[1667:10690] starting screenresolution argv=screenresolution get 09:58:35 INFO - 2016-07-05 09:58:35.654 screenresolution[1667:10690] Display 0: 1600x1200x32@60 09:58:35 INFO - 2016-07-05 09:58:35.660 screenresolution[1668:10693] starting screenresolution argv=screenresolution list 09:58:35 INFO - Available Modes on Display 0 09:58:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:58:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:58:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:58:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:58:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:58:35 INFO - Intel Iris: 09:58:35 INFO - Chipset Model: Intel Iris 09:58:35 INFO - Type: GPU 09:58:35 INFO - Bus: Built-In 09:58:35 INFO - VRAM (Dynamic, Max): 1536 MB 09:58:35 INFO - Vendor: Intel (0x8086) 09:58:35 INFO - Device ID: 0x0a2e 09:58:35 INFO - Revision ID: 0x0009 09:58:35 INFO - Displays: 09:58:35 INFO - Display: 09:58:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:58:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:58:35 INFO - Display Serial Number: 1600x1200 60 09:58:35 INFO - Main Display: Yes 09:58:35 INFO - Mirror: Off 09:58:35 INFO - Online: Yes 09:58:35 INFO - Rotation: Supported 09:58:35 INFO - Return code: 0 09:58:35 INFO - Running post-action listener: _resource_record_post_action 09:58:35 INFO - ##### 09:58:35 INFO - ##### Finished run-tests step (success) 09:58:35 INFO - ##### 09:58:35 INFO - Running post-run listener: _resource_record_post_run 09:58:35 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 32992768; Write bytes: 705589760; Read time: 317; Write time: 1880 09:58:35 INFO - TinderboxPrint: CPU usage
53.7% 09:58:35 INFO - TinderboxPrint: I/O read bytes / time
32,992,768 / 317 09:58:35 INFO - TinderboxPrint: I/O write bytes / time
705,589,760 / 1,880 09:58:35 INFO - TinderboxPrint: CPU idle
2,243.9 (46.3%) 09:58:35 INFO - TinderboxPrint: CPU system
967.4 (20.0%) 09:58:35 INFO - TinderboxPrint: CPU user
1,630.4 (33.7%) 09:58:35 INFO - TinderboxPrint: Swap in / out
435,621,888 / 0 09:58:35 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 407151104; Write bytes: 401119232; Read time: 30218; Write time: 776 09:58:35 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 29142528; Write bytes: 294681088; Read time: 275; Write time: 1064 09:58:36 INFO - Running post-run listener: _upload_blobber_files 09:58:36 INFO - Blob upload gear active. 09:58:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:58:36 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:58:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:58:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:58:36 INFO - (blobuploader) - INFO - Open directory for files ... 09:58:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:58:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:58:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:37 INFO - (blobuploader) - INFO - Done attempting. 09:58:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:58:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:58:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:38 INFO - (blobuploader) - INFO - Done attempting. 09:58:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:58:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:58:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:58:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:58:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:58:41 INFO - (blobuploader) - INFO - Done attempting. 09:58:41 INFO - (blobuploader) - INFO - Iteration through files over. 09:58:41 INFO - Return code: 0 09:58:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:58:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:58:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdecfe2de0987c05eb64c9aec9ed1d15a1b4ca957ab03bb997cbc05e5553199919a6cb146b14ac94c88236622bb943fdb8f882371dedf882c74d728b5ad30e2a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/784bc0ca2148310c6f5a8b7762994b78526ef00cd7d9a8a4c402fdfbc9045f870a055dc79b8f75d2a3132fdb38e4eb7dab5cb1d23284b23db5ccb544780cccc9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7a40fd6bf2d8937617a4db769612f9e80f4696ac02c7daf94ae327a1d416c8e469b1ca10da5d8f0324b0bc517486d187024731dc03d45a6db6d9c075b861c66"} 09:58:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:58:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:58:41 INFO - Contents: 09:58:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdecfe2de0987c05eb64c9aec9ed1d15a1b4ca957ab03bb997cbc05e5553199919a6cb146b14ac94c88236622bb943fdb8f882371dedf882c74d728b5ad30e2a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/784bc0ca2148310c6f5a8b7762994b78526ef00cd7d9a8a4c402fdfbc9045f870a055dc79b8f75d2a3132fdb38e4eb7dab5cb1d23284b23db5ccb544780cccc9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7a40fd6bf2d8937617a4db769612f9e80f4696ac02c7daf94ae327a1d416c8e469b1ca10da5d8f0324b0bc517486d187024731dc03d45a6db6d9c075b861c66"} 09:58:41 INFO - Running post-run listener: copy_logs_to_upload_dir 09:58:41 INFO - Copying logs to upload dir... 09:58:41 INFO - mkdir: /builds/slave/test/build/upload/logs 09:58:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.194773 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-05 09:58:41.687168) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 09:58:41.690400) ========= 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.ggboIhxFml/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.amvnHJBo8U/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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/bdecfe2de0987c05eb64c9aec9ed1d15a1b4ca957ab03bb997cbc05e5553199919a6cb146b14ac94c88236622bb943fdb8f882371dedf882c74d728b5ad30e2a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/784bc0ca2148310c6f5a8b7762994b78526ef00cd7d9a8a4c402fdfbc9045f870a055dc79b8f75d2a3132fdb38e4eb7dab5cb1d23284b23db5ccb544780cccc9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7a40fd6bf2d8937617a4db769612f9e80f4696ac02c7daf94ae327a1d416c8e469b1ca10da5d8f0324b0bc517486d187024731dc03d45a6db6d9c075b861c66"} build_url:https://queue.taskcluster.net/v1/task/JhZjf3CIQLuQT9IaeDlOqw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010466 build_url: 'https://queue.taskcluster.net/v1/task/JhZjf3CIQLuQT9IaeDlOqw/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/bdecfe2de0987c05eb64c9aec9ed1d15a1b4ca957ab03bb997cbc05e5553199919a6cb146b14ac94c88236622bb943fdb8f882371dedf882c74d728b5ad30e2a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/784bc0ca2148310c6f5a8b7762994b78526ef00cd7d9a8a4c402fdfbc9045f870a055dc79b8f75d2a3132fdb38e4eb7dab5cb1d23284b23db5ccb544780cccc9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b7a40fd6bf2d8937617a4db769612f9e80f4696ac02c7daf94ae327a1d416c8e469b1ca10da5d8f0324b0bc517486d187024731dc03d45a6db6d9c075b861c66"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 09:58:41.722198) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:58:41.722491) ========= 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.ggboIhxFml/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.amvnHJBo8U/Listeners TMPDIR=/var/folders/_6/4rdc3g_j72l0vnxz7yb01s0000000w/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.005269 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 09:58:41.770634) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 09:58:41.770930) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 09:58:41.771234) ========= ========= Total master_lag: 2.83 =========