builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0338 starttime: 1469132589.22 results: success (0) buildid: 20160721101219 builduid: 81f5b3ef69b344f78121921179e1e781 revision: b9d5aa8be125a6323c33b8a9950b38e449a24a3b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 13:23:09.226704) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 13:23:09.227344) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 13:23:09.227670) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 13:23:09.341029) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 13:23:09.341389) ========= 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.FjVJCUSRUS/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.rHS1cAAuzD/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-21 13:23:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-07-21 13:23:09 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.161904 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 13:23:09.530464) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 13:23:09.531235) ========= 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.FjVJCUSRUS/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.rHS1cAAuzD/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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.025203 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 13:23:09.582550) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-07-21 13:23:09.582968) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b9d5aa8be125a6323c33b8a9950b38e449a24a3b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev b9d5aa8be125a6323c33b8a9950b38e449a24a3b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FjVJCUSRUS/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.rHS1cAAuzD/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-21 13:23:09,664 truncating revision to first 12 chars 2016-07-21 13:23:09,664 Setting DEBUG logging. 2016-07-21 13:23:09,664 attempt 1/10 2016-07-21 13:23:09,664 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b9d5aa8be125?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 13:23:10,362 unpacking tar archive at: fx-team-b9d5aa8be125/testing/mozharness/ program finished with exit code 0 elapsedTime=0.979776 ========= master_lag: 2.80 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-07-21 13:23:13.360110) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 13:23:13.360431) ========= script_repo_revision: b9d5aa8be125a6323c33b8a9950b38e449a24a3b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 13:23:13.360841) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 13:23:13.361121) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 13:23:13.972806) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-07-21 13:23:13.973533) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FjVJCUSRUS/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.rHS1cAAuzD/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 13:23:14 INFO - MultiFileLogger online at 20160721 13:23:14 in /builds/slave/test 13:23:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 13:23:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:23:14 INFO - {'append_to_log': False, 13:23:14 INFO - 'base_work_dir': '/builds/slave/test', 13:23:14 INFO - 'blob_upload_branch': 'Fx-Team', 13:23:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:23:14 INFO - 'branch': 'Fx-Team', 13:23:14 INFO - 'buildbot_json_path': 'buildprops.json', 13:23:14 INFO - 'config_files': ('talos/mac_config.py',), 13:23:14 INFO - 'default_actions': ('clobber', 13:23:14 INFO - 'read-buildbot-config', 13:23:14 INFO - 'download-and-extract', 13:23:14 INFO - 'populate-webroot', 13:23:14 INFO - 'create-virtualenv', 13:23:14 INFO - 'install', 13:23:14 INFO - 'run-tests'), 13:23:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:23:14 INFO - 'download_minidump_stackwalk': True, 13:23:14 INFO - 'download_symbols': 'ondemand', 13:23:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:23:14 INFO - 'tooltool.py': '/tools/tooltool.py', 13:23:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:23:14 INFO - '/tools/misc-python/virtualenv.py')}, 13:23:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:23:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:23:14 INFO - 'installer_path': 'installer.exe', 13:23:14 INFO - 'log_level': 'info', 13:23:14 INFO - 'log_name': 'talos', 13:23:14 INFO - 'log_to_console': True, 13:23:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:23:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:23:14 INFO - 'opt_config_files': (), 13:23:14 INFO - 'pip_index': False, 13:23:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:23:14 INFO - 'cmd': ('bash', 13:23:14 INFO - '-c', 13:23:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:23:14 INFO - 'enabled': True, 13:23:14 INFO - 'halt_on_failure': False, 13:23:14 INFO - 'name': 'check_screen_resolution'},), 13:23:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:23:14 INFO - 'cmd': ('bash', 13:23:14 INFO - '-c', 13:23:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:23:14 INFO - 'enabled': True, 13:23:14 INFO - 'halt_on_failure': False, 13:23:14 INFO - 'name': 'check_screen_resolution'},), 13:23:14 INFO - 'run_cmd_checks_enabled': True, 13:23:14 INFO - 'sps_profile': False, 13:23:14 INFO - 'sps_profile_interval': 0, 13:23:14 INFO - 'suite': 'dromaeojs', 13:23:14 INFO - 'system_bits': '32', 13:23:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:23:14 INFO - 'title': 't-yosemite-r7-0338', 13:23:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:23:14 INFO - 'use_talos_json': True, 13:23:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:23:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:23:14 INFO - 'work_dir': 'build'} 13:23:14 INFO - [mozharness: 2016-07-21 20:23:14.161384Z] Running clobber step. 13:23:14 INFO - Running pre-action listener: _resource_record_pre_action 13:23:14 INFO - Running main action method: clobber 13:23:14 INFO - rmtree: /builds/slave/test/build 13:23:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:23:16 INFO - Running post-action listener: _resource_record_post_action 13:23:16 INFO - [mozharness: 2016-07-21 20:23:16.202154Z] Finished clobber step (success) 13:23:16 INFO - [mozharness: 2016-07-21 20:23:16.202282Z] Running read-buildbot-config step. 13:23:16 INFO - Running pre-action listener: _resource_record_pre_action 13:23:16 INFO - Running main action method: read_buildbot_config 13:23:16 INFO - Using buildbot properties: 13:23:16 INFO - { 13:23:16 INFO - "project": "", 13:23:16 INFO - "product": "firefox", 13:23:16 INFO - "script_repo_revision": "production", 13:23:16 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 13:23:16 INFO - "repository": "", 13:23:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 13:23:16 INFO - "buildid": "20160721101219", 13:23:16 INFO - "pgo_build": "False", 13:23:16 INFO - "basedir": "/builds/slave/test", 13:23:16 INFO - "buildnumber": 113, 13:23:16 INFO - "slavename": "t-yosemite-r7-0338", 13:23:16 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 13:23:16 INFO - "platform": "yosemite_r7", 13:23:16 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 13:23:16 INFO - "branch": "fx-team", 13:23:16 INFO - "repo_path": "integration/fx-team", 13:23:16 INFO - "revision": "b9d5aa8be125a6323c33b8a9950b38e449a24a3b", 13:23:16 INFO - "stage_platform": "macosx64", 13:23:16 INFO - "builduid": "81f5b3ef69b344f78121921179e1e781", 13:23:16 INFO - "slavebuilddir": "test" 13:23:16 INFO - } 13:23:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 13:23:16 INFO - Running post-action listener: _resource_record_post_action 13:23:16 INFO - [mozharness: 2016-07-21 20:23:16.205731Z] Finished read-buildbot-config step (success) 13:23:16 INFO - [mozharness: 2016-07-21 20:23:16.205869Z] Running download-and-extract step. 13:23:16 INFO - Running pre-action listener: _resource_record_pre_action 13:23:16 INFO - Running main action method: download_and_extract 13:23:16 INFO - mkdir: /builds/slave/test/build/tests 13:23:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:23:16 INFO - https://queue.taskcluster.net/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:23:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 13:23:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 13:23:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/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 13:23:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/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 13:23:17 INFO - Downloaded 1199 bytes. 13:23:17 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 13:23:17 INFO - Using the following test package requirements: 13:23:17 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 13:23:17 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:23:17 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 13:23:17 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:23:17 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 13:23:17 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:23:17 INFO - u'jsshell-mac.zip'], 13:23:17 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:23:17 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 13:23:17 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 13:23:17 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:23:17 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 13:23:17 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:23:17 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 13:23:17 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:23:17 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 13:23:17 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 13:23:17 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 13:23:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:23:17 INFO - https://queue.taskcluster.net/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:23:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 13:23:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 13:23:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/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 13:23:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/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 13:23:18 INFO - Downloaded 18806583 bytes. 13:23:18 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'] 13:23:18 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 13:23:21 INFO - Return code: 0 13:23:21 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 13:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:23:21 INFO - https://queue.taskcluster.net/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:23:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 13:23:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 13:23:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/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 13:23:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/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 13:23:22 INFO - Downloaded 18806583 bytes. 13:23:22 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'] 13:23:22 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 13:23:26 INFO - Return code: 0 13:23:26 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')]} 13:23:26 INFO - https://queue.taskcluster.net/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:23:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 13:23:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 13:23:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/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 13:23:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/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 13:23:27 INFO - Downloaded 14403317 bytes. 13:23:27 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'] 13:23:27 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 13:23:27 INFO - Return code: 0 13:23:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:23:27 INFO - https://queue.taskcluster.net/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 13:23:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 13:23:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 13:23:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 13:23:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XDqxjlgeRNW-t2TBlbmPuw/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 13:23:31 INFO - Downloaded 112772094 bytes. 13:23:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 13:23:31 INFO - mkdir: /builds/slave/test/properties 13:23:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:23:31 INFO - Writing to file /builds/slave/test/properties/build_url 13:23:31 INFO - Contents: 13:23:31 INFO - build_url:https://queue.taskcluster.net/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 13:23:32 INFO - Running post-action listener: _resource_record_post_action 13:23:32 INFO - Running post-action listener: set_extra_try_arguments 13:23:32 INFO - [mozharness: 2016-07-21 20:23:32.282158Z] Finished download-and-extract step (success) 13:23:32 INFO - [mozharness: 2016-07-21 20:23:32.282330Z] Running populate-webroot step. 13:23:32 INFO - Running pre-action listener: _resource_record_pre_action 13:23:32 INFO - Running main action method: populate_webroot 13:23:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:23:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:23:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:23:32 INFO - '--tppagecycles', 13:23:32 INFO - '7'], 13:23:32 INFO - 'tests': ['tsvgm']}}, 13:23:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:23:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:23:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:23:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:23:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:23:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:23:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:23:32 INFO - 'talos_options': ['--disable-e10s'], 13:23:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:23:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:23:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:23:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:23:32 INFO - 'talos_options': ['--disable-e10s'], 13:23:32 INFO - 'tests': ['damp', 'tps']}, 13:23:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:23:32 INFO - 'tests': ['damp', 'tps']}, 13:23:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:23:32 INFO - 'tests': ['dromaeo_dom']}, 13:23:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:23:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:23:32 INFO - 'tests': ['basic_compositor_video']}, 13:23:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:23:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:23:32 INFO - 'tests': ['a11yr', 13:23:32 INFO - 'ts_paint', 13:23:32 INFO - 'tpaint', 13:23:32 INFO - 'sessionrestore', 13:23:32 INFO - 'sessionrestore_no_auto_restore', 13:23:32 INFO - 'tabpaint']}, 13:23:32 INFO - 'other-e10s': {'tests': ['a11yr', 13:23:32 INFO - 'ts_paint', 13:23:32 INFO - 'tpaint', 13:23:32 INFO - 'sessionrestore', 13:23:32 INFO - 'sessionrestore_no_auto_restore', 13:23:32 INFO - 'tabpaint']}, 13:23:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:23:32 INFO - 'ts_paint', 13:23:32 INFO - 'tpaint', 13:23:32 INFO - 'sessionrestore', 13:23:32 INFO - 'sessionrestore_no_auto_restore', 13:23:32 INFO - 'tabpaint']}, 13:23:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:23:32 INFO - 'ts_paint', 13:23:32 INFO - 'tpaint', 13:23:32 INFO - 'sessionrestore', 13:23:32 INFO - 'sessionrestore_no_auto_restore', 13:23:32 INFO - 'tabpaint']}, 13:23:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:23:32 INFO - 'tests': ['a11yr', 13:23:32 INFO - 'ts_paint', 13:23:32 INFO - 'tpaint', 13:23:32 INFO - 'sessionrestore', 13:23:32 INFO - 'sessionrestore_no_auto_restore', 13:23:32 INFO - 'tabpaint']}, 13:23:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:23:32 INFO - 'tests': ['a11yr', 13:23:32 INFO - 'ts_paint', 13:23:32 INFO - 'tpaint', 13:23:32 INFO - 'sessionrestore', 13:23:32 INFO - 'sessionrestore_no_auto_restore', 13:23:32 INFO - 'tabpaint']}, 13:23:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:23:32 INFO - 'tests': ['tsvgx', 13:23:32 INFO - 'tsvgr_opacity', 13:23:32 INFO - 'tart', 13:23:32 INFO - 'tscrollx', 13:23:32 INFO - 'cart']}, 13:23:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:23:32 INFO - 'tsvgr_opacity', 13:23:32 INFO - 'tart', 13:23:32 INFO - 'tscrollx', 13:23:32 INFO - 'cart']}, 13:23:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:23:32 INFO - 'talos_options': ['--disable-e10s'], 13:23:32 INFO - 'tests': ['tp5o']}, 13:23:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:23:32 INFO - 'tests': ['tp5o']}, 13:23:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:23:32 INFO - 'talos_options': ['--disable-e10s', 13:23:32 INFO - '--xperf_path', 13:23:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:23:32 INFO - 'tests': ['tp5n']}, 13:23:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:23:32 INFO - 'talos_options': ['--xperf_path', 13:23:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:23:32 INFO - 'tests': ['tp5n']}}, 13:23:32 INFO - 'talos.zip': {'path': '', 13:23:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:23:32 INFO - Running post-action listener: _resource_record_post_action 13:23:32 INFO - [mozharness: 2016-07-21 20:23:32.301189Z] Finished populate-webroot step (success) 13:23:32 INFO - [mozharness: 2016-07-21 20:23:32.301318Z] Running create-virtualenv step. 13:23:32 INFO - Running pre-action listener: _resource_record_pre_action 13:23:32 INFO - Running main action method: create_virtualenv 13:23:32 INFO - Creating virtualenv /builds/slave/test/build/venv 13:23:32 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 13:23:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:23:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:23:32 INFO - Using real prefix '/tools/python27' 13:23:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:23:33 INFO - Installing distribute.............................................................................................................................................................................................done. 13:23:35 INFO - Installing pip.................done. 13:23:36 INFO - Return code: 0 13:23:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:23:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:23:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:23:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109a68a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109afd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd21ca4d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ccd30>, '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 0x109731db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099c4b20>, '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.FjVJCUSRUS/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.rHS1cAAuzD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 13:23:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:23:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FjVJCUSRUS/Render', 13:23:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:23:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:23:36 INFO - 'HOME': '/Users/cltbld', 13:23:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:23:36 INFO - 'LOGNAME': 'cltbld', 13:23:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:36 INFO - 'MOZ_NO_REMOTE': '1', 13:23:36 INFO - 'NO_EM_RESTART': '1', 13:23:36 INFO - 'PAGER': '/bin/cat', 13:23:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:23:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:36 INFO - 'PWD': '/builds/slave/test', 13:23:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:23:36 INFO - 'SHELL': '/bin/bash', 13:23:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHS1cAAuzD/Listeners', 13:23:36 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 13:23:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:23:36 INFO - 'USER': 'cltbld', 13:23:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:23:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:23:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:36 INFO - 'XPC_FLAGS': '0x0', 13:23:36 INFO - 'XPC_SERVICE_NAME': '0', 13:23:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:23:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:23:36 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 13:23:36 INFO - Cleaning up... 13:23:36 INFO - Return code: 0 13:23:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:23:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:23:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:23:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109a68a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109afd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd21ca4d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ccd30>, '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 0x109731db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099c4b20>, '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.FjVJCUSRUS/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.rHS1cAAuzD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 13:23:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:23:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FjVJCUSRUS/Render', 13:23:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:23:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:23:36 INFO - 'HOME': '/Users/cltbld', 13:23:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:23:36 INFO - 'LOGNAME': 'cltbld', 13:23:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:36 INFO - 'MOZ_NO_REMOTE': '1', 13:23:36 INFO - 'NO_EM_RESTART': '1', 13:23:36 INFO - 'PAGER': '/bin/cat', 13:23:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:23:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:36 INFO - 'PWD': '/builds/slave/test', 13:23:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:23:36 INFO - 'SHELL': '/bin/bash', 13:23:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHS1cAAuzD/Listeners', 13:23:36 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 13:23:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:23:36 INFO - 'USER': 'cltbld', 13:23:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:23:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:23:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:36 INFO - 'XPC_FLAGS': '0x0', 13:23:36 INFO - 'XPC_SERVICE_NAME': '0', 13:23:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:23:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:23:36 INFO - Downloading/unpacking psutil>=3.1.1 13:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:23:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:23:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:23:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:23:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:23:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:23:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:23:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:23:39 INFO - Installing collected packages: psutil 13:23:39 INFO - Running setup.py install for psutil 13:23:39 INFO - building 'psutil._psutil_osx' extension 13:23:39 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 13:23:39 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 13:23:39 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 13:23:39 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 13:23:40 INFO - building 'psutil._psutil_posix' extension 13:23:40 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 13:23:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:23:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:23:40 INFO - ^ 13:23:40 INFO - 1 warning generated. 13:23:40 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 13:23:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:23:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:23:40 INFO - Successfully installed psutil 13:23:40 INFO - Cleaning up... 13:23:40 INFO - Return code: 0 13:23:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:23:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:23:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:23:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:23:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:23:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:40 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 0x109a68a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109afd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd21ca4d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ccd30>, '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 0x109731db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099c4b20>, '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.FjVJCUSRUS/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.rHS1cAAuzD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 13:23:40 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 13:23:40 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 13:23:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FjVJCUSRUS/Render', 13:23:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:23:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:23:40 INFO - 'HOME': '/Users/cltbld', 13:23:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:23:40 INFO - 'LOGNAME': 'cltbld', 13:23:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:40 INFO - 'MOZ_NO_REMOTE': '1', 13:23:40 INFO - 'NO_EM_RESTART': '1', 13:23:40 INFO - 'PAGER': '/bin/cat', 13:23:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:23:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:40 INFO - 'PWD': '/builds/slave/test', 13:23:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:23:40 INFO - 'SHELL': '/bin/bash', 13:23:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHS1cAAuzD/Listeners', 13:23:40 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 13:23:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:23:40 INFO - 'USER': 'cltbld', 13:23:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:23:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:23:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:40 INFO - 'XPC_FLAGS': '0x0', 13:23:40 INFO - 'XPC_SERVICE_NAME': '0', 13:23:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:23:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:23:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:23:40 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 13:23:40 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 13:23:40 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 13:23:40 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 13:23:40 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 13:23:40 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 13:23:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:23:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:23:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:23:43 INFO - Installing collected packages: mozsystemmonitor 13:23:43 INFO - Running setup.py install for mozsystemmonitor 13:23:43 INFO - Successfully installed mozsystemmonitor 13:23:43 INFO - Cleaning up... 13:23:43 INFO - Return code: 0 13:23:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:23: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')]} 13:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:23:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23: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')]} 13:23:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:23:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:43 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 0x109a68a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109afd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd21ca4d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ccd30>, '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 0x109731db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099c4b20>, '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.FjVJCUSRUS/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.rHS1cAAuzD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 13:23:43 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 13:23:43 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 13:23:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FjVJCUSRUS/Render', 13:23:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:23:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:23:43 INFO - 'HOME': '/Users/cltbld', 13:23:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:23:43 INFO - 'LOGNAME': 'cltbld', 13:23:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:43 INFO - 'MOZ_NO_REMOTE': '1', 13:23:43 INFO - 'NO_EM_RESTART': '1', 13:23:43 INFO - 'PAGER': '/bin/cat', 13:23:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:23:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:43 INFO - 'PWD': '/builds/slave/test', 13:23:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:23:43 INFO - 'SHELL': '/bin/bash', 13:23:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHS1cAAuzD/Listeners', 13:23:43 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 13:23:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:23:43 INFO - 'USER': 'cltbld', 13:23:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:23:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:23:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:43 INFO - 'XPC_FLAGS': '0x0', 13:23:43 INFO - 'XPC_SERVICE_NAME': '0', 13:23:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:23:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:23:43 INFO - Downloading/unpacking jsonschema==2.5.1 13:23:43 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 13:23:43 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 13:23:43 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 13:23:43 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 13:23:43 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 13:23:43 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 13:23:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:23:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:23:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:23:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:23:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:23:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:23:46 INFO - Downloading functools32-3.2.3-2.tar.gz 13:23:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:23:46 INFO - warning: no files found matching '*.txt' 13:23:46 INFO - no previously-included directories found matching 'build' 13:23:46 INFO - no previously-included directories found matching 'dist' 13:23:46 INFO - no previously-included directories found matching '.git*' 13:23:46 INFO - Installing collected packages: jsonschema, functools32 13:23:46 INFO - Running setup.py install for functools32 13:23:46 INFO - warning: no files found matching '*.txt' 13:23:46 INFO - no previously-included directories found matching 'build' 13:23:46 INFO - no previously-included directories found matching 'dist' 13:23:46 INFO - no previously-included directories found matching '.git*' 13:23:46 INFO - Successfully installed jsonschema functools32 13:23:46 INFO - Cleaning up... 13:23:46 INFO - Return code: 0 13:23:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:23:46 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')]} 13:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:23:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:46 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')]} 13:23:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:23:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:46 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 0x109a68a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109afd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd21ca4d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ccd30>, '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 0x109731db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099c4b20>, '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.FjVJCUSRUS/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.rHS1cAAuzD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 13:23:46 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 13:23:46 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 13:23:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FjVJCUSRUS/Render', 13:23:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:23:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:23:46 INFO - 'HOME': '/Users/cltbld', 13:23:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:23:46 INFO - 'LOGNAME': 'cltbld', 13:23:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:46 INFO - 'MOZ_NO_REMOTE': '1', 13:23:46 INFO - 'NO_EM_RESTART': '1', 13:23:46 INFO - 'PAGER': '/bin/cat', 13:23:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:23:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:46 INFO - 'PWD': '/builds/slave/test', 13:23:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:23:46 INFO - 'SHELL': '/bin/bash', 13:23:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHS1cAAuzD/Listeners', 13:23:46 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 13:23:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:23:46 INFO - 'USER': 'cltbld', 13:23:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:23:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:23:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:46 INFO - 'XPC_FLAGS': '0x0', 13:23:46 INFO - 'XPC_SERVICE_NAME': '0', 13:23:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:23:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:23:47 INFO - Downloading/unpacking blobuploader==1.2.4 13:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:23:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:23:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:23:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:23:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:23:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:23:49 INFO - Downloading blobuploader-1.2.4.tar.gz 13:23:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:23:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:23:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:23:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:23:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:23:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:23:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:23:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:23:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:23:50 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 13:23:50 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 13:23:50 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 13:23:50 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 13:23:50 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 13:23:50 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 13:23:50 INFO - Downloading docopt-0.6.1.tar.gz 13:23:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:23:51 INFO - Installing collected packages: blobuploader, requests, docopt 13:23:51 INFO - Running setup.py install for blobuploader 13:23:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:23:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:23:51 INFO - Running setup.py install for requests 13:23:51 INFO - Running setup.py install for docopt 13:23:51 INFO - Successfully installed blobuploader requests docopt 13:23:51 INFO - Cleaning up... 13:23:51 INFO - Return code: 0 13:23:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:23:51 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')]} 13:23:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:23:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:51 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')]} 13:23:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:23:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:51 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 0x109a68a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109afd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd21ca4d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ccd30>, '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 0x109731db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099c4b20>, '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.FjVJCUSRUS/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.rHS1cAAuzD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 13:23:51 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 13:23:51 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 13:23:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FjVJCUSRUS/Render', 13:23:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:23:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:23:51 INFO - 'HOME': '/Users/cltbld', 13:23:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:23:51 INFO - 'LOGNAME': 'cltbld', 13:23:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:51 INFO - 'MOZ_NO_REMOTE': '1', 13:23:51 INFO - 'NO_EM_RESTART': '1', 13:23:51 INFO - 'PAGER': '/bin/cat', 13:23:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:23:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:51 INFO - 'PWD': '/builds/slave/test', 13:23:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:23:51 INFO - 'SHELL': '/bin/bash', 13:23:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHS1cAAuzD/Listeners', 13:23:51 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 13:23:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:23:51 INFO - 'USER': 'cltbld', 13:23:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:23:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:23:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:51 INFO - 'XPC_FLAGS': '0x0', 13:23:51 INFO - 'XPC_SERVICE_NAME': '0', 13:23:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:23:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:23:52 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-OJvpdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:23:52 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-u_GISm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:23:52 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-yYRRne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:23:52 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-11bxk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:23:52 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-KpsY39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:23:52 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-ODiD1b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:23:52 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-ywr1JB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:23:53 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-3uLmlm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:23:53 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-670YJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:23:53 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-oSzCjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:23:53 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-wsxjtV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:23:53 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-C2ckSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:23:53 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-e8O94N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:23:53 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-0ezWUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:23:53 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-ghKHAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:23:53 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-ykixvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:23:53 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-ysOLkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:23:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:23:54 INFO - Running setup.py install for manifestparser 13:23:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:23:54 INFO - Running setup.py install for mozcrash 13:23:54 INFO - Running setup.py install for mozdebug 13:23:54 INFO - Running setup.py install for mozdevice 13:23:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:23:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:23:54 INFO - Running setup.py install for mozfile 13:23:54 INFO - Running setup.py install for mozhttpd 13:23:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:23:54 INFO - Running setup.py install for mozinfo 13:23:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:23:55 INFO - Running setup.py install for mozInstall 13:23:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:23:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:23:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:23:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:23:55 INFO - Running setup.py install for mozleak 13:23:55 INFO - Running setup.py install for mozlog 13:23:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:23:55 INFO - Running setup.py install for moznetwork 13:23:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:23:55 INFO - Running setup.py install for mozprocess 13:23:55 INFO - Running setup.py install for mozprofile 13:23:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:23:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:23:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:23:56 INFO - Running setup.py install for mozrunner 13:23:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:23:56 INFO - Running setup.py install for mozscreenshot 13:23:56 INFO - Running setup.py install for moztest 13:23:56 INFO - Running setup.py install for mozversion 13:23:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:23:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:23:56 INFO - Cleaning up... 13:23:56 INFO - Return code: 0 13:23:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:23:56 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')]} 13:23:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:23:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:56 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')]} 13:23:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:23:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:23:56 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 0x109a68a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109afd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd21ca4d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ccd30>, '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 0x109731db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099c4b20>, '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.FjVJCUSRUS/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.rHS1cAAuzD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 13:23:56 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 13:23:56 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 13:23:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FjVJCUSRUS/Render', 13:23:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:23:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:23:56 INFO - 'HOME': '/Users/cltbld', 13:23:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:23:56 INFO - 'LOGNAME': 'cltbld', 13:23:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:23:56 INFO - 'MOZ_NO_REMOTE': '1', 13:23:56 INFO - 'NO_EM_RESTART': '1', 13:23:56 INFO - 'PAGER': '/bin/cat', 13:23:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:23:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:23:56 INFO - 'PWD': '/builds/slave/test', 13:23:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:23:56 INFO - 'SHELL': '/bin/bash', 13:23:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHS1cAAuzD/Listeners', 13:23:56 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 13:23:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:23:56 INFO - 'USER': 'cltbld', 13:23:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:23:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:23:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:23:56 INFO - 'XPC_FLAGS': '0x0', 13:23:56 INFO - 'XPC_SERVICE_NAME': '0', 13:23:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:23:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:23:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:23:57 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-9ip_ik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:23:57 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)) 13:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:23:57 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-VXcEaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:23:57 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)) 13:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:23:57 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-PYXTuJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:23:57 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)) 13:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:23:57 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-kWYAif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:23:57 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)) 13:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:23:57 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-at2on1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:23:57 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)) 13:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:23:57 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-GJVJ6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:23:57 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)) 13:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:23:57 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-9AGMC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:23:57 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)) 13:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:23:57 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-paFvFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:23:57 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)) 13:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:23:57 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-TfQSVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:23:57 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)) 13:23:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:23:57 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-Oc1sFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:23:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:23:58 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-_DGgEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:23:58 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)) 13:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:23:58 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-mvs_i0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:23:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:23:58 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-_r1JY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:23:58 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)) 13:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:23:58 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-7DRpdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:23:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:23:58 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-EGHWCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:23:58 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)) 13:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:23:58 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-DV3myG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:23:58 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)) 13:23:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:23:58 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-WmGgFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:23:58 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)) 13:23:58 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)) 13:23:58 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)) 13:23:58 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)) 13:23:58 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)) 13:23:58 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)) 13:23:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:23:58 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 13:23:58 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 13:23:58 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 13:23:58 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 13:23:58 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 13:23:58 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 13:24:01 INFO - Downloading blessings-1.6.tar.gz 13:24:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:24:01 INFO - Installing collected packages: blessings 13:24:01 INFO - Running setup.py install for blessings 13:24:01 INFO - Successfully installed blessings 13:24:01 INFO - Cleaning up... 13:24:01 INFO - Return code: 0 13:24:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:24:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:24:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:24:01 INFO - Reading from file tmpfile_stdout 13:24:01 INFO - Current package versions: 13:24:01 INFO - blessings == 1.6 13:24:01 INFO - blobuploader == 1.2.4 13:24:01 INFO - docopt == 0.6.1 13:24:01 INFO - functools32 == 3.2.3-2 13:24:01 INFO - jsonschema == 2.5.1 13:24:01 INFO - manifestparser == 1.1 13:24:01 INFO - mozInstall == 1.12 13:24:01 INFO - mozcrash == 0.17 13:24:01 INFO - mozdebug == 0.1 13:24:01 INFO - mozdevice == 0.48 13:24:01 INFO - mozfile == 1.2 13:24:01 INFO - mozhttpd == 0.7 13:24:01 INFO - mozinfo == 0.9 13:24:01 INFO - mozleak == 0.1 13:24:01 INFO - mozlog == 3.3 13:24:01 INFO - moznetwork == 0.27 13:24:01 INFO - mozprocess == 0.23 13:24:01 INFO - mozprofile == 0.28 13:24:01 INFO - mozrunner == 6.12 13:24:01 INFO - mozscreenshot == 0.1 13:24:01 INFO - mozsystemmonitor == 0.3 13:24:01 INFO - moztest == 0.7 13:24:01 INFO - mozversion == 1.4 13:24:01 INFO - psutil == 3.1.1 13:24:01 INFO - requests == 1.2.3 13:24:01 INFO - wsgiref == 0.1.2 13:24:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:24:01 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')]} 13:24:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:24:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24: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')]} 13:24:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:24:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24:02 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 0x109a68a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109afd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd21ca4d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ccd30>, '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 0x109731db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099c4b20>, '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.FjVJCUSRUS/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.rHS1cAAuzD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 13:24:02 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 13:24:02 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 13:24:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FjVJCUSRUS/Render', 13:24:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:24:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:24:02 INFO - 'HOME': '/Users/cltbld', 13:24:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:24:02 INFO - 'LOGNAME': 'cltbld', 13:24:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:24:02 INFO - 'MOZ_NO_REMOTE': '1', 13:24:02 INFO - 'NO_EM_RESTART': '1', 13:24:02 INFO - 'PAGER': '/bin/cat', 13:24:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:24:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:24:02 INFO - 'PWD': '/builds/slave/test', 13:24:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:24:02 INFO - 'SHELL': '/bin/bash', 13:24:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHS1cAAuzD/Listeners', 13:24:02 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 13:24:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:24:02 INFO - 'USER': 'cltbld', 13:24:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:24:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:24:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:24:02 INFO - 'XPC_FLAGS': '0x0', 13:24:02 INFO - 'XPC_SERVICE_NAME': '0', 13:24:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:24:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:24:02 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)) 13:24:02 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)) 13:24:02 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)) 13:24:02 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)) 13:24:02 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)) 13:24:02 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)) 13:24:02 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)) 13:24:02 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)) 13:24:02 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)) 13:24:02 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)) 13:24:02 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)) 13:24:02 INFO - Cleaning up... 13:24:02 INFO - Return code: 0 13:24:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:24: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')]} 13:24:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:24:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24: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')]} 13:24:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:24:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:24: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109a68a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109afd618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd21ca4d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099ccd30>, '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 0x109731db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099c4b20>, '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.FjVJCUSRUS/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.rHS1cAAuzD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 13:24: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', 'jsonschema'] in /builds/slave/test/build 13:24: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 jsonschema 13:24:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FjVJCUSRUS/Render', 13:24:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:24:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:24:02 INFO - 'HOME': '/Users/cltbld', 13:24:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:24:02 INFO - 'LOGNAME': 'cltbld', 13:24:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:24:02 INFO - 'MOZ_NO_REMOTE': '1', 13:24:02 INFO - 'NO_EM_RESTART': '1', 13:24:02 INFO - 'PAGER': '/bin/cat', 13:24:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:24:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:24:02 INFO - 'PWD': '/builds/slave/test', 13:24:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:24:02 INFO - 'SHELL': '/bin/bash', 13:24:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHS1cAAuzD/Listeners', 13:24:02 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 13:24:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:24:02 INFO - 'USER': 'cltbld', 13:24:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:24:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:24:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:24:02 INFO - 'XPC_FLAGS': '0x0', 13:24:02 INFO - 'XPC_SERVICE_NAME': '0', 13:24:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:24:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:24:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:24:02 INFO - Cleaning up... 13:24:02 INFO - Return code: 0 13:24:02 INFO - Running post-action listener: _resource_record_post_action 13:24:02 INFO - Running post-action listener: _start_resource_monitoring 13:24:02 INFO - Starting resource monitoring. 13:24:02 INFO - [mozharness: 2016-07-21 20:24:02.842110Z] Finished create-virtualenv step (success) 13:24:02 INFO - [mozharness: 2016-07-21 20:24:02.843108Z] Running install step. 13:24:02 INFO - Running pre-action listener: _resource_record_pre_action 13:24:02 INFO - Running main action method: install 13:24:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:24:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:24:03 INFO - Reading from file tmpfile_stdout 13:24:03 INFO - Detecting whether we're running mozinstall >=1.0... 13:24:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:24:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:24:03 INFO - Reading from file tmpfile_stdout 13:24:03 INFO - Output received: 13:24:03 INFO - Usage: mozinstall [options] installer 13:24:03 INFO - Options: 13:24:03 INFO - -h, --help show this help message and exit 13:24:03 INFO - -d DEST, --destination=DEST 13:24:03 INFO - Directory to install application into. [default: 13:24:03 INFO - "/builds/slave/test"] 13:24:03 INFO - --app=APP Application being installed. [default: firefox] 13:24:03 INFO - mkdir: /builds/slave/test/build/application 13:24:03 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'] 13:24:03 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 13:24:37 INFO - Reading from file tmpfile_stdout 13:24:37 INFO - Output received: 13:24:37 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:24:37 INFO - Running post-action listener: _resource_record_post_action 13:24:37 INFO - [mozharness: 2016-07-21 20:24:37.670617Z] Finished install step (success) 13:24:37 INFO - [mozharness: 2016-07-21 20:24:37.670759Z] Running run-tests step. 13:24:37 INFO - Running pre-action listener: _resource_record_pre_action 13:24:37 INFO - Running main action method: run_tests 13:24:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:24:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:24:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:24:37 INFO - 2016-07-21 13:24:37.685 screenresolution[1640:7505] starting screenresolution argv=screenresolution get 13:24:37 INFO - 2016-07-21 13:24:37.688 screenresolution[1640:7505] Display 0: 1600x1200x32@60 13:24:37 INFO - 2016-07-21 13:24:37.693 screenresolution[1641:7507] starting screenresolution argv=screenresolution list 13:24:37 INFO - Available Modes on Display 0 13:24:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:24:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:24:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:24:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:24:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:24:37 INFO - Intel Iris: 13:24:37 INFO - Chipset Model: Intel Iris 13:24:37 INFO - Type: GPU 13:24:37 INFO - Bus: Built-In 13:24:37 INFO - VRAM (Dynamic, Max): 1536 MB 13:24:37 INFO - Vendor: Intel (0x8086) 13:24:37 INFO - Device ID: 0x0a2e 13:24:37 INFO - Revision ID: 0x0009 13:24:37 INFO - Displays: 13:24:37 INFO - Display: 13:24:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:24:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:24:37 INFO - Display Serial Number: 1600x1200 60 13:24:37 INFO - Main Display: Yes 13:24:37 INFO - Mirror: Off 13:24:37 INFO - Online: Yes 13:24:37 INFO - Rotation: Supported 13:24:37 INFO - Return code: 0 13:24:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:24:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:24:37 INFO - Python 2.7.3 13:24:37 INFO - Return code: 0 13:24:37 INFO - grabbing minidump binary from tooltool 13:24: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')]} 13:24:37 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 0x1099ccd30>, '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 0x109731db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1099c4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:24:37 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 13:24:37 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 13:24:37 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 13:24:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:24:38 INFO - Return code: 0 13:24:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:24:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:24:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:24:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:24:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:24:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0338', '--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 13:24:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0338 --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 13:24:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FjVJCUSRUS/Render', 13:24:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:24:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:24:38 INFO - 'HOME': '/Users/cltbld', 13:24:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:24:38 INFO - 'LOGNAME': 'cltbld', 13:24:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:24:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:24:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:24:38 INFO - 'MOZ_NO_REMOTE': '1', 13:24:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:24:38 INFO - 'NO_EM_RESTART': '1', 13:24:38 INFO - 'PAGER': '/bin/cat', 13:24:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:24:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:24:38 INFO - 'PWD': '/builds/slave/test', 13:24:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:24:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:24:38 INFO - 'SHELL': '/bin/bash', 13:24:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rHS1cAAuzD/Listeners', 13:24:38 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 13:24:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:24:38 INFO - 'USER': 'cltbld', 13:24:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:24:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:24:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:24:38 INFO - 'XPC_FLAGS': '0x0', 13:24:38 INFO - 'XPC_SERVICE_NAME': '0', 13:24:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:24:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0338', '--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 13:24:38 INFO - mozversion application_buildid: 20160721101219 13:24:38 INFO - mozversion application_changeset: b9d5aa8be125a6323c33b8a9950b38e449a24a3b 13:24:38 INFO - mozversion application_display_name: Nightly 13:24:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:24:38 INFO - mozversion application_name: Firefox 13:24:38 INFO - mozversion application_remotingname: firefox 13:24:38 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 13:24:38 INFO - mozversion application_vendor: Mozilla 13:24:38 INFO - mozversion application_version: 50.0a1 13:24:38 INFO - mozversion platform_buildid: 20160721101219 13:24:38 INFO - mozversion platform_changeset: b9d5aa8be125a6323c33b8a9950b38e449a24a3b 13:24:38 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 13:24:38 INFO - mozversion platform_version: 50.0a1 13:24:38 INFO - using testdate: 1469132678 13:24:38 INFO - actual date: 1469132678 13:24:38 INFO - starting webserver on 'localhost:49278' 13:24:38 INFO - SUITE-START | Running 2 tests 13:24:38 INFO - TEST-START | dromaeo_css 13:24:38 INFO - operating with platform_type : mac_ 13:24:38 INFO - Initialising browser for dromaeo_css test... 13:24:38 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpUxoowj/profile http://localhost:49278/getInfo.html) 13:24:40 INFO - PROCESS | 1651 | JavaScript error: , line 0: uncaught exception: undefined 13:24:40 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 13:24:40 INFO - PROCESS | 1651 | colorDepth:24 13:24:40 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 13:24:40 INFO - PROCESS | 1651 | __metrics 13:24:40 INFO - PROCESS | 1651 | 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" 13:24:40 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:24:41 INFO - TEST-INFO | 1651: exit 0 13:24:41 INFO - Browser initialized. 13:24:41 INFO - Running cycle 1/1 for dromaeo_css test... 13:24:41 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpUxoowj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:24:53 INFO - PROCESS | 1653 | RSS: Main: 245600256 13:24:53 INFO - PROCESS | 1653 | 13:25:45 INFO - PROCESS | 1653 | 1469132745462 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] 13:27:56 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 13:27:57 INFO - PROCESS | 1653 | RSS: Main: 377278464 13:27:57 INFO - PROCESS | 1653 | 13:31:00 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 13:31:01 INFO - PROCESS | 1653 | RSS: Main: 387690496 13:31:01 INFO - PROCESS | 1653 | 13:34:03 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 13:34:04 INFO - PROCESS | 1653 | RSS: Main: 379719680 13:34:04 INFO - PROCESS | 1653 | 13:37:06 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 13:37:07 INFO - PROCESS | 1653 | RSS: Main: 378724352 13:37:07 INFO - PROCESS | 1653 | 13:40:10 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 13:40:11 INFO - PROCESS | 1653 | RSS: Main: 344383488 13:40:11 INFO - PROCESS | 1653 | 13:43:14 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 13:43:14 INFO - PROCESS | 1653 | RSS: Main: 418078720 13:43:14 INFO - PROCESS | 1653 | 13:43:14 INFO - PROCESS | 1653 | __start_tp_report 13:43:14 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 13:43:14 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 13:43:14 INFO - PROCESS | 1653 | |i|pagename|runs| 13:43:14 INFO - PROCESS | 1653 | |0;dojo.html;3331.533585298058;3380.7464440166996;3382.188274814044;3396.167938854981;3446.138465383654;5590.413006634306;5680.06278963971;5734.885302293952;5783.624064435814;5817.1565123057135;5699.658020518761;5740.913886291709;5840.328362238344;5866.677332746697;5885.146653735206;5137.3064636274;5212.374515058198;5237.554807841338;5243.554297884684;5342.626016178869;5340.198970154488;5340.732963351837;5378.731063446831;5391.245225668409;5413.242146099549;5124.718140502271;5163.922541161885;5165.922511162298;5242.580593552507;5252.606054545905;5224.425313215544;5235.424103348629;5238.476152384731;5258.789648414097;5259.789608415659;5136.512031357015;5173.870653233662;5177.48225177483;5207.713575753332;5220.791168353261;5647.040003199425;5710.229119068914;5720.6281591696825;5752.194692743019;5756.453136951983;6145.784897528565;6240.875182496325;6243.313235544087;6247.031710084899;6281.905771413432;6129.759719006968;6261.154744109533;6262.091996660459;6275.686215689243;6288.654124023177;5705.800296989669;5766.798162064308;5844.298684157886;5928.881422371572;5950.7917222896995;5351.57187425005;6200.131981522591;6243.906341404882;6253.812385628438;6266.060090986389;6101.420365065312;6103.529224525481;6106.175666284995;6112.663803490762;6123.020316749299;6129.662868542273;6203.565750397429;6269.996800511987;6295.118683384329;6304.338044505353;6148.1085242639565;6203.193584834033;6207.099970504251;6209.130721698965;6218.81343559694;6191.659458729813;6196.3803619638575;6209.037599172136;6209.472194863487;6217.316095229521;4856.587190088811;4862.5380588844;4919.4834542373255;4936.234883593049;4985.950140498621;4434.3348497724955;4440.289553671397;4455;4480.61914737251;4547.931781023287;5662.009148399014;5708.457696518824;5719.456651618173;5740.397258287903;5787.565932555075;6113.810786210604;6217.316095229521;6241.812745617639;6313.810585682437;6315;5676.517496012885;5707.429257074259;5718.79984200551;5724.341700704473;5737.512311453574;5396.1366181410785;5459.590530710133;5472.315960504937;5479.424660410679;5490.478404551562;6188.381161883775;6229.906551401733;6282.497400207881;6285.025820997753;6300.873982520416;6294.24469063697;6296.779612713533;6304.936950630435;6312.400321969406;6317.7157027932935;6202.7208775606105;6269.373062693694;6286.622802631857;6290.779822706183;6304.873902521832;6101.368489499298;6107.977837958671;6130.172426722335;6176.135341052346;6189.195404597373;6302.117703521419;6304.117423560613;6313.747450101944;6325.620462772248;6355.90466142999;6133.693315334126;6188.628682279078;6195.349488303844;6197.690115494297;6217.751289948352;5848.005839007464;5902.881942361215;6098.0086717533295;6099.207103076749;6169.136320914986;6006.789762358297;6055.609087387051;6059.909101363395;6066.803337684944;6119.602225855483;6125.111858780363;6133.417325354084;6154.292256390393;6157.876842463036;6182.938170618416;5854.677992710442;5933.821985340447;5944.73248703818;6039.577229593886;6063.302720187235 13:43:14 INFO - PROCESS | 1653 | |1;ext.html;496.5828703888734;505.31529777151957;527.3987654074355;548.1531034551617;552.1193696054792;30586.776528938844;42357.94105147373;43713;43753.343699844525;46094.07811843761;44578.88552786182;44948.55051449485;44970.55029449704;45026.649200524036;45081;8707.216350528452;9677.758056048602;9859;9867.555959981783;9874.358166719141;8680.8697869532;11906.523739050428;12103.757924841499;12117.212381195193;12400;12105.939470302636;12158.452869620844;12205.145639805218;12205.267683938991;12206.267623942547;8351.415400921938;11852.585159519376;11979.580714674988;12071.698207544881;12094.697632559213;11645.59240426581;12021.639350819401;12024.038076953822;12056.45745941426;12061.396930153545;42271.1545769086;42479.93800155021;42519.57480425187;42558.574414255774;42724.57275427237;3502.702270307027;3649.178934739689;3656.341858465475;3701.0747313171705;4225.429567008446;32885.342293154004;32990.50514242288;33369.6663033369;33453;33472.83263583667;3249.675032496755;3363.243270264171;3420.3159368126476;3425.811580363079;3429.2455659754814;488899;491421;492154;493442.9999999964;495993;65236;65472.67263663651;65671.67164164149;65723.74210063183;65761.6711916447;68886;68924.31075689179;69090;69111.65444172747;69207.30792692008;17114;17281.654366912593;17288.913555432395;17326.74009889853;17420.216090275968;12107.636770896886;12329.383530823423;13037.282949437871;13211.867881321064;13284.335783210994;18245.908770456062;18794.248230070643;18832.34086806955;18840.623187536174;18866;6210.658413787285;7393.334599885929;7432.145303290083;7433.331000209899;7542.622868856535;937.9062093790567;1000.7126589154592;1084.2843723142687;1115.7322242661864;1137.4369687004985;26007.219783406526;27396;30885;31126.844365778026;31349.43252837349;932.2821427500786;1003.9598416063276;1048.0096308988086;1127.4588197665166;1181.3502573584494;14944.775828362583;15420;15433.691326173639;16366;16422.32229650645;677024;680720.5963970148;681569;682618;682917;61607.691961540804;61825.690871545354;62034.37965620376;62077.68961155165;62147.37852621506;1407.2963518240879;1428.728541577097;1470.0958910270322;1472.3595236072342;1473.0719646622558;28452.715472845008;29598.704012959595;30619;30680;31416.842915785273;53385;53613.92772144613;53632;53663.73168134134;53698.73150634222;517309;518937;520733;521530;522070;43005;43985.78007109944;44236.55763442325;44897.32654010127;44928.550714492434;43161.78419107884;49680.75798104932;49864;51069;51722.22416663678;40934.38598421086;41779.9555011115;42269.440095430786;42276.57723422726;42342.51801187067;40483.98790030273;41202;41300;42374;43168.35247471353;38793.80603096967;39383.01542461461;39506.60493395029;40144.397834033094;40190.39714404345;32521.837390812896;34033.31933361269;35577.822110890316;36591.26817463689;36654.63345366512;26004.21987340383;26059.670170853842;26169;26368.60129596515;26920 13:43:14 INFO - PROCESS | 1653 | |2;jquery.html;344.26499423730326;374.23469005882964;374.9531308586425;380.12191836856863;406.0498433665223;15535.611609709764;16266;20477;20697;20830.89584552079;20458.363330933527;20634.8650824205;21039;21047.368578942616;21480.140794368206;4892.510748925097;5369.328833895763;5436.945630543693;5554.750036248358;5621.297337832771;3940.5271367435803;5513.3108361454815;5601.411851755568;5604.243427137346;5616.747246373923;5899.115132730082;6114.113453549233;6175.783847565336;6219.875602487948;6222.719977600997;6199.907001394983;6202.751889924398;6291.653959032252;6852.862942741117;6934.341237582422;6082.847928801771;6088.208532890696;6092.573519853612;6108.938910610881;6112.419320164578;13555.186688798703;13604.86395136046;13731.862681373159;13925.885929125747;15637.765433518507;1902.2105826082159;2223.49971256466;2306.5156317173414;2338.777816107467;2783.956016493815;10220.89779102207;10635.255532112755;12598.244105353708;15789;16545.420910268203;1875.7373967644542;1917.3960202536157;1943.2227109156308;1945.7956914523913;1958;107675;119293;121228;125468.37265813704;129633;10898.836517452244;11569.363684997406;11634.6509604712;11647.59233426826;11890.28658280506;12168.2699038058;14726.042807217496;14736.410543578137;14737.410503579951;14744.083269174938;8472.406931514857;8759.204363105548;8792.824143517093;8891.822163556693;8930.374873758774;8869;8882.734902599796;8907.198352148338;9077.909220907839;9079.45523268606;4547.431571053618;4612.76936153191;4616.3998680172035;4630.398048253773;4713.6936099153445;1504.6840163565773;1839.6596629623562;1894.895780732073;1921.9423417297503;1939.9806001940083;843.4138273899704;900.1178844732068;913.6345461815328;924.1036194890945;945.3146468810057;21102.155913763585;22129.114835406403;22818;22847.543049138923;22873.656895146585;913.2830727878717;940.6989763275823;947.9004704506066;981.7790997025755;1032.7624646331235;9766.267529935283;11142.052925501423;11807.763844723057;11852.822207666892;11873.050155987501;572480;576247;585291;603589;603875;75176;94813;117857.23214151623;129585.35207324092;141037;875.465965760898;939.2901479232328;979.7256768104942;996.820572296979;999.5352161245056;16350.427735029218;21019.054142563928;21200.469988250417;21257.04343304524;21336.03987820583;58756.70621646864;58837;60538.69730651495;60724.481887951384;61235.69382153061;83855.580722096;84754;86994;89748.55125724591;90609;5487.314085739283;5513.365962914316;7650.693972241048;8230.341572674291;8453.196946290092;13337.599872003855;13376.398062087035;13630.522931697344;13863.445462181804;13867.029307948347;11319.886801131883;11527.942360288145;11644.53421863116;11703.531858725897;11793.41032948332;11441.027512661183;11459.770804584028;11752.23610465317;11875.524979000744;11903.226290291102;11548.480318385526;11695.941520292345;12115.878841211475;12129.21160124589;12208.572699955459;7610.581418022062;8050.476719013245;8317.875231871647;8382.413231073766;8401;9250;11687.474063666985;11959.581414650445;11959.701007474883;11961.282323060645 13:43:14 INFO - PROCESS | 1653 | |3;mootools.html;1456.934437950292;1632.6326576520285;1783.6076063266084;1963.469863136954;2003.0285311623868;8399.874001889977;8902.020777714446;8919.420237684546;8928.19646231839;8936.59785309661;7061.682224299906;7694.384449244061;7967;8678.566071696405;8717.956410217956;2326.1393284484766;2375.7861792438675;2383.0825132324007;2384.332386931662;2387;2337.4857531343164;2350.306659535441;2371.5849726297915;2379.3813608461837;2385.8807059647033;2337.836351455399;2355.4346956730433;2367.1951536477595;2378.4053986503372;2388.164142550111;2327.2669109230537;2334.054707843318;2339.274824804299;2346.5189636124555;2360.563295790267;2336.205690065369;2346.835721499496;2350.7061617297836;2353.6116540770754;2361.409647588103;8830.20528152463;8868.689595864114;8951.597178127;8991;8994.910050899472;5169.974150129263;5343.786248550054;5344.839654810362;5350.170723537819;5368.463153684639;3514.4025515662397;3571.3035957988204;3577.6422357764277;3581.176329444216;3585.766925149857;1001.8345538968159;1021.4126877045728;1054.915606751458;1087.6954452753241;1104.950277237518;11167.888321116847;11361.261518001304;11410.714732131763;11413.54345826166;11427.428628568538;4461.286194208976;4535.795889184995;4676.368690226844;4740.1230772307235;4760.880977975578;8243.845861579384;9546.761330966781;9705.805883882284;9770.022997700315;9771.21830253578;5258.3689957204615;5276.736163191825;5276.788928442819;5277.973610131925;5278.841634750964;4314.374415709704;4491.258942274553;4670.5329467053025;4795.736234507136;4837.298591704183;3834.884953451401;3839.0786211309087;3969.4641223435046;3998.8600398985895;4031.838726450968;1256.2839181666363;1256.673264951101;1259.9055070869722;1263.7914744067125;1288.0597164070282;873.8776571280026;953.1993125774383;968.2592816495387;971.7221853262937;1021.0810270756378;6032.698365081728;6072.635641861502;6160.507159427235;6188.257409110935;6202.255729312511;374.4289957814355;449.5302408982621;450.28404836310426;454.6885383512305;461.63069544364373;4009.4787677601726;4025.798710064485;4069.1251380953236;4142.627163555234;4175;10147.34042287249;10649.041586257274;10655.520501577448;10703.571857125784;10719.517621707042;10651.09465695409;10672.786544269227;10728.141748660088;10731.195160363004;10734.033936945712;7734.4585878989055;8104.230098140785;8127.878081828777;8183.79540511492;8219.876701849476;6030.3668114847305;6076.848078797889;6104.359042300494;6249.093881387264;6282.937170628235;9979.850302245617;10125.038121378457;10155.984401559785;10158.08577228053;10241.897581024094;9827.459489728133;9834;10139.493025348556;10280.126189273697;10327.586896524055;6645.1361323027695;7037.049998250171;7251.8187045324285;7388.371988381048;7438.033055702724;8307.169283071644;8501.447405918596;8512.829743405222;8534.231919127309;8543.231109200202;7368.263173682589;7425.405967522477;7559.20628334017;7696.692132314644;7723.806904827199;7315.743948961761;7382.52013619113;7497.475176737576;7542.4720269582795;7635.160132385544;7017.7192912282935;7147.035150254649;7155.606441645759;7189.137303523611;7196.676149573386;4852.466228714909;4985.850424487271;5125.282460455464;5231.136862417658;5260.842174734764;5018;5051.974740126423;5207.453217412269;5227.111391063452;5234.450382709761 13:43:14 INFO - PROCESS | 1653 | |4;prototype.html;270.69276371318546;299.96850330715273;301.8641611274927;312.0529193896523;321.96136463624356;4579.862604121873;4978.2532620107;5224.869378265541;5300;5320.494553017466;4561.863144105674;4793.08931303053;5098.898022039557;5134.717590532519;5147.790269286724;2992.9102126936227;3290.8190049547266;3933.4069701770686;3967;4025.879223623296;3338.933221335572;4027.5166979962446;4031.6976226782963;4040.878773636781;4090.6522945549664;3742.569604495477;3957.366821308591;3991.6607088397377;4007.83968641254;4017.312728653958;3997.2205419943125;4007.9599204007877;4008.518977722692;4020.336644453647;4025.5571887092397;3441.483777433405;3458.9654103459147;3473.218525831693;3473.39215637265;3484.860605575774;5082.669626474304;5113.846584602468;5131.923021154686;5133.794648214067;5178;4034.6570541504007;4248.7693960670895;4560.065186636764;4570.428696412949;4643.744594047326;4660.720356778605;5458.918116228259;5547.77808887644;5604.8318550443555;5607.55139588836;3315.6187038490643;3722.088088418328;3778.8110594470168;3805.0487378155462;3831.927060423086;5317.867053323621;5481.561475081984;5801.3038435388025;5831.017454065595;5861.20873682056;3131.4519959007084;3378.8986330410125;3863.613638636114;3878.631530004645;3884.834549635098;3464.566929133858;3666.6433419634373;3748.156664750409;3923.588023257574;3938.6849052075763;3905.8047097645003;4336.804843782037;4567.246404343313;4567.65742569302;4569.405977222939;5562.86092847674;5601.467860553241;5601.747921343549;5618.37067250501;5652.406497317723;1367.282176857158;1387.8681525255085;1398.7970345503063;1401.7546929287334;1410.1820943852747;871.3900269811157;906.8549032154824;924.6763632728491;946.5267366316841;955.8900726416551;5018.0214858103045;5025.673331233458;5041.395032596039;5044.445111037783;5083.500367391628;5317.042932272228;5571.582131340085;5638.210650508932;5702.258706368146;5716.1997320375185;4800.759962001886;5082.923756143661;5127.717975511308;5193.584513238932;5263.578913686896;2489.962650560243;2992;2994.9550756738668;2995.5806187133817;3001.1446737679657;5522.337319521683;5822.679752613562;5857.384974577693;5861.325947516005;5870.739765190432;5314.9901518712095;5534.374984385234;5713.342965558931;5854.765809367577;5974.595969947059;5009.298698182258;5315.309009828775;5399.406065332811;5843;5934.37689042653;5408.1071624930955;5697.772089116389;5725.484706376446;5816.952948469316;5873.65053978412;5647.887042259214;5666.2350582670815;5668.773249069991;5669.829905102853;5686.57350698691;5478.123500239943;5576.553875689854;5895.7052147393315;5928.881422371443;5945.494632956247;3386.797686821143;3624.7100231981904;3788.962110378861;3822.5795162532654;3872.3997780344093;4821.686590371614;5655.709437677747;5709.628874123168;5737.684427356536;5785;5128.564072053768;5260.0531904257605;5352.625316227826;5631.380548139619;5637.689927054052;5503.889922201613;5538.529225015919;5546.084895992116;5573.860653483695;5632.746526406239;5129.8717532062;5292.470752924677;5442.673439593637;5447.210154527492;5471.2613797138465;5316.840494785162;5429.782808687608;5553.527950124285;5588.692621905671;5598.804041858515;4546.4544254689645;5452.209429632601;5454.59090568201;5463.945360546502;5471.480209380103 13:43:14 INFO - PROCESS | 1653 | |5;yui.html;711.5090587494628;719.0329007484933;743.4944237918216;758.3781299334548;817.4563914996528;15085.170315632637;16353.83646163538;16375.099369534671;16377.754333685005;16458.75311870323;14673.339699713511;14803.925980370082;14888.181150036744;14899.255037248122;14926.626834329147;710.105267363121;734.6583838515089;737.5464089584893;883.2051153961422;916.0107084348887;899.8380291547519;917.1195652173888;917.2891009467683;917.6467060181844;918.8392031394479;958.228625956105;958.5399008475936;959.6785076999179;961.966331178409;962.8170647576973;733.8348871503869;916.1434059154711;951.4148798488949;952.5237381309346;954.398728800858;705.4109818301724;711.5161690050763;711.5872793779596;712.6436781609195;712.8396110875063;15194.164320962343;15239.542813715607;15257.6185595361;15270.618234544225;15303.923480382638;13913.513027044004;14011.299435028208;14041.368138433692;14139.575812725634;14297.64255893611;12186.2078964867;12212.511499540007;12258.387080645933;12376.62870113898;12441.813372799415;3863.651021228268;4551.68138230324;4608.907821843578;4701.623870090419;4785.282207668877;104420;106932.4653376736;107587.46206268997;108744;109323;11204.439778011067;11221.831672524919;11257.662270131908;11260.718482038015;11404.600838970597;22620.886895565418;22706.886465567568;22731.772682273295;22732.545349093256;23015.53968920612;7945.801354966172;7978.441509094308;8022.318102961197;8074;8172;4389.517153113155;4391.297392417262;4393.121375724868;4433.135538569948;4581.198290299184;3327.600687917417;3438.9312213755584;3448.758446959143;3456;3456.879009234665;595.3213336796056;608.6165715599144;624.2883113250898;626.1140486211959;628.2680677011322;531.5402177116844;548.347466514846;598.494272339872;628.5097623853402;641.6374748267214;7226;7378.741744038933;8934.597943092576;8956.283497320199;9235.861462078074;570.647446253262;590.4715279824619;623.8565130020121;639.8240483866881;641.788209890735;4168.583141685868;4352.347147927836;4432.202203603382;4652.441706995114;4769.642276829251;123382;151881.24059379855;152427;154010;157849.2107539455;121588;129275;131523;131831.34084329518;135536;112349.4382528082;113513;114007;114098.85901140883;115883;8720.91279087201;8739.38824282294;8794.736157915273;8812.427192232484;9072.773180670272;56803.71598142148;57963.4203657958;61365.69317153386;61906.69046654918;62572.687136564025;62384;72225;85542.73487181577;90185.54907225422;95306.57040144542;11538.307701537935;13665.726685466434;13790.241536715577;13808.102473339603;14287.785683214552;15112.319945602694;15251.466198682992;15441.5367538974;15882.761758573863;16115;13985.58043258704;14104.788428173792;14276.357563909442;14422.350994205077;14739.787966158363;12206.32865192423;13753.931230343784;14174.716505670036;14318.427262909783;14633.487827925972;12028.218165819193;12175.14773965814;12448.128630996105;12449.315287659265;12453.377331133226;11158.427635497384;11559.76880462403;11575.71060723455;11693.181477296508;11868.169228153947;8497.627098387162;8569.271611913056;8900.759651739701;10982.231243813176;11317.151213659174 13:43:14 INFO - PROCESS | 1653 | __end_tp_report 13:43:14 INFO - PROCESS | 1653 | __start_cc_report 13:43:14 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1265 13:43:14 INFO - PROCESS | 1653 | __end_cc_report 13:43:14 INFO - PROCESS | 1653 | __startTimestamp1469133794907__endTimestamp 13:43:14 INFO - PROCESS | 1653 | 13:43:14 INFO - PROCESS | 1653 | ------- Summary: start ------- 13:43:14 INFO - PROCESS | 1653 | Number of tests: 6 13:43:14 INFO - PROCESS | 1653 | 13:43:14 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5763.36 Median:6039.58 stddev:629.51 (10.4%) stddev-sans-first:601.85 13:43:14 INFO - PROCESS | 1653 | Values: 3331.5 3380.7 3382.2 3396.2 3446.1 5590.4 5680.1 5734.9 5783.6 5817.2 5699.7 5740.9 5840.3 5866.7 5885.1 5137.3 5212.4 5237.6 5243.6 5342.6 5340.2 5340.7 5378.7 5391.2 5413.2 5124.7 5163.9 5165.9 5242.6 5252.6 5224.4 5235.4 5238.5 5258.8 5259.8 5136.5 5173.9 5177.5 5207.7 5220.8 5647.0 5710.2 5720.6 5752.2 5756.5 6145.8 6240.9 6243.3 6247.0 6281.9 6129.8 6261.2 6262.1 6275.7 6288.7 5705.8 5766.8 5844.3 5928.9 5950.8 5351.6 6200.1 6243.9 6253.8 6266.1 6101.4 6103.5 6106.2 6112.7 6123.0 6129.7 6203.6 6270.0 6295.1 6304.3 6148.1 6203.2 6207.1 6209.1 6218.8 6191.7 6196.4 6209.0 6209.5 6217.3 4856.6 4862.5 4919.5 4936.2 4986.0 4434.3 4440.3 4455.0 4480.6 4547.9 5662.0 5708.5 5719.5 5740.4 5787.6 6113.8 6217.3 6241.8 6313.8 6315.0 5676.5 5707.4 5718.8 5724.3 5737.5 5396.1 5459.6 5472.3 5479.4 5490.5 6188.4 6229.9 6282.5 6285.0 6300.9 6294.2 6296.8 6304.9 6312.4 6317.7 6202.7 6269.4 6286.6 6290.8 6304.9 6101.4 6108.0 6130.2 6176.1 6189.2 6302.1 6304.1 6313.7 6325.6 6355.9 6133.7 6188.6 6195.3 6197.7 6217.8 5848.0 5902.9 6098.0 6099.2 6169.1 6006.8 6055.6 6059.9 6066.8 6119.6 6125.1 6133.4 6154.3 6157.9 6182.9 5854.7 5933.8 5944.7 6039.6 6063.3 13:43:14 INFO - PROCESS | 1653 | 13:43:14 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:71733.14 Median:40337.19 stddev:152156.18 (377.2%) stddev-sans-first:152489.01 13:43:14 INFO - PROCESS | 1653 | Values: 496.6 505.3 527.4 548.2 552.1 30586.8 42357.9 43713.0 43753.3 46094.1 44578.9 44948.6 44970.6 45026.6 45081.0 8707.2 9677.8 9859.0 9867.6 9874.4 8680.9 11906.5 12103.8 12117.2 12400.0 12105.9 12158.5 12205.1 12205.3 12206.3 8351.4 11852.6 11979.6 12071.7 12094.7 11645.6 12021.6 12024.0 12056.5 12061.4 42271.2 42479.9 42519.6 42558.6 42724.6 3502.7 3649.2 3656.3 3701.1 4225.4 32885.3 32990.5 33369.7 33453.0 33472.8 3249.7 3363.2 3420.3 3425.8 3429.2 488899.0 491421.0 492154.0 493443.0 495993.0 65236.0 65472.7 65671.7 65723.7 65761.7 68886.0 68924.3 69090.0 69111.7 69207.3 17114.0 17281.7 17288.9 17326.7 17420.2 12107.6 12329.4 13037.3 13211.9 13284.3 18245.9 18794.2 18832.3 18840.6 18866.0 6210.7 7393.3 7432.1 7433.3 7542.6 937.9 1000.7 1084.3 1115.7 1137.4 26007.2 27396.0 30885.0 31126.8 31349.4 932.3 1004.0 1048.0 1127.5 1181.4 14944.8 15420.0 15433.7 16366.0 16422.3 677024.0 680720.6 681569.0 682618.0 682917.0 61607.7 61825.7 62034.4 62077.7 62147.4 1407.3 1428.7 1470.1 1472.4 1473.1 28452.7 29598.7 30619.0 30680.0 31416.8 53385.0 53613.9 53632.0 53663.7 53698.7 517309.0 518937.0 520733.0 521530.0 522070.0 43005.0 43985.8 44236.6 44897.3 44928.6 43161.8 49680.8 49864.0 51069.0 51722.2 40934.4 41780.0 42269.4 42276.6 42342.5 40484.0 41202.0 41300.0 42374.0 43168.4 38793.8 39383.0 39506.6 40144.4 40190.4 32521.8 34033.3 35577.8 36591.3 36654.6 26004.2 26059.7 26169.0 26368.6 26920.0 13:43:14 INFO - PROCESS | 1653 | 13:43:14 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:34744.96 Median:2561.37 stddev:98231.12 (3835.1%) stddev-sans-first:98472.72 13:43:14 INFO - PROCESS | 1653 | Values: 344.3 374.2 375.0 380.1 406.0 15535.6 16266.0 20477.0 20697.0 20830.9 20458.4 20634.9 21039.0 21047.4 21480.1 4892.5 5369.3 5436.9 5554.8 5621.3 3940.5 5513.3 5601.4 5604.2 5616.7 5899.1 6114.1 6175.8 6219.9 6222.7 6199.9 6202.8 6291.7 6852.9 6934.3 6082.8 6088.2 6092.6 6108.9 6112.4 13555.2 13604.9 13731.9 13925.9 15637.8 1902.2 2223.5 2306.5 2338.8 2784.0 10220.9 10635.3 12598.2 15789.0 16545.4 1875.7 1917.4 1943.2 1945.8 1958.0 107675.0 119293.0 121228.0 125468.4 129633.0 10898.8 11569.4 11634.7 11647.6 11890.3 12168.3 14726.0 14736.4 14737.4 14744.1 8472.4 8759.2 8792.8 8891.8 8930.4 8869.0 8882.7 8907.2 9077.9 9079.5 4547.4 4612.8 4616.4 4630.4 4713.7 1504.7 1839.7 1894.9 1921.9 1940.0 843.4 900.1 913.6 924.1 945.3 21102.2 22129.1 22818.0 22847.5 22873.7 913.3 940.7 947.9 981.8 1032.8 9766.3 11142.1 11807.8 11852.8 11873.1 572480.0 576247.0 585291.0 603589.0 603875.0 75176.0 94813.0 117857.2 129585.4 141037.0 875.5 939.3 979.7 996.8 999.5 16350.4 21019.1 21200.5 21257.0 21336.0 58756.7 58837.0 60538.7 60724.5 61235.7 83855.6 84754.0 86994.0 89748.6 90609.0 5487.3 5513.4 7650.7 8230.3 8453.2 13337.6 13376.4 13630.5 13863.4 13867.0 11319.9 11527.9 11644.5 11703.5 11793.4 11441.0 11459.8 11752.2 11875.5 11903.2 11548.5 11695.9 12115.9 12129.2 12208.6 7610.6 8050.5 8317.9 8382.4 8401.0 9250.0 11687.5 11959.6 11959.7 11961.3 13:43:14 INFO - PROCESS | 1653 | 13:43:14 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5697.97 Median:4778.31 stddev:3175.31 (66.5%) stddev-sans-first:3168.22 13:43:14 INFO - PROCESS | 1653 | Values: 1456.9 1632.6 1783.6 1963.5 2003.0 8399.9 8902.0 8919.4 8928.2 8936.6 7061.7 7694.4 7967.0 8678.6 8718.0 2326.1 2375.8 2383.1 2384.3 2387.0 2337.5 2350.3 2371.6 2379.4 2385.9 2337.8 2355.4 2367.2 2378.4 2388.2 2327.3 2334.1 2339.3 2346.5 2360.6 2336.2 2346.8 2350.7 2353.6 2361.4 8830.2 8868.7 8951.6 8991.0 8994.9 5170.0 5343.8 5344.8 5350.2 5368.5 3514.4 3571.3 3577.6 3581.2 3585.8 1001.8 1021.4 1054.9 1087.7 1105.0 11167.9 11361.3 11410.7 11413.5 11427.4 4461.3 4535.8 4676.4 4740.1 4760.9 8243.8 9546.8 9705.8 9770.0 9771.2 5258.4 5276.7 5276.8 5278.0 5278.8 4314.4 4491.3 4670.5 4795.7 4837.3 3834.9 3839.1 3969.5 3998.9 4031.8 1256.3 1256.7 1259.9 1263.8 1288.1 873.9 953.2 968.3 971.7 1021.1 6032.7 6072.6 6160.5 6188.3 6202.3 374.4 449.5 450.3 454.7 461.6 4009.5 4025.8 4069.1 4142.6 4175.0 10147.3 10649.0 10655.5 10703.6 10719.5 10651.1 10672.8 10728.1 10731.2 10734.0 7734.5 8104.2 8127.9 8183.8 8219.9 6030.4 6076.8 6104.4 6249.1 6282.9 9979.9 10125.0 10156.0 10158.1 10241.9 9827.5 9834.0 10139.5 10280.1 10327.6 6645.1 7037.0 7251.8 7388.4 7438.0 8307.2 8501.4 8512.8 8534.2 8543.2 7368.3 7425.4 7559.2 7696.7 7723.8 7315.7 7382.5 7497.5 7542.5 7635.2 7017.7 7147.0 7155.6 7189.1 7196.7 4852.5 4985.9 5125.3 5231.1 5260.8 5018.0 5052.0 5207.5 5227.1 5234.5 13:43:14 INFO - PROCESS | 1653 | 13:43:14 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4508.54 Median:5130.90 stddev:1393.39 (27.2%) stddev-sans-first:1360.51 13:43:14 INFO - PROCESS | 1653 | Values: 270.7 300.0 301.9 312.1 322.0 4579.9 4978.3 5224.9 5300.0 5320.5 4561.9 4793.1 5098.9 5134.7 5147.8 2992.9 3290.8 3933.4 3967.0 4025.9 3338.9 4027.5 4031.7 4040.9 4090.7 3742.6 3957.4 3991.7 4007.8 4017.3 3997.2 4008.0 4008.5 4020.3 4025.6 3441.5 3459.0 3473.2 3473.4 3484.9 5082.7 5113.8 5131.9 5133.8 5178.0 4034.7 4248.8 4560.1 4570.4 4643.7 4660.7 5458.9 5547.8 5604.8 5607.6 3315.6 3722.1 3778.8 3805.0 3831.9 5317.9 5481.6 5801.3 5831.0 5861.2 3131.5 3378.9 3863.6 3878.6 3884.8 3464.6 3666.6 3748.2 3923.6 3938.7 3905.8 4336.8 4567.2 4567.7 4569.4 5562.9 5601.5 5601.7 5618.4 5652.4 1367.3 1387.9 1398.8 1401.8 1410.2 871.4 906.9 924.7 946.5 955.9 5018.0 5025.7 5041.4 5044.4 5083.5 5317.0 5571.6 5638.2 5702.3 5716.2 4800.8 5082.9 5127.7 5193.6 5263.6 2490.0 2992.0 2995.0 2995.6 3001.1 5522.3 5822.7 5857.4 5861.3 5870.7 5315.0 5534.4 5713.3 5854.8 5974.6 5009.3 5315.3 5399.4 5843.0 5934.4 5408.1 5697.8 5725.5 5817.0 5873.7 5647.9 5666.2 5668.8 5669.8 5686.6 5478.1 5576.6 5895.7 5928.9 5945.5 3386.8 3624.7 3789.0 3822.6 3872.4 4821.7 5655.7 5709.6 5737.7 5785.0 5128.6 5260.1 5352.6 5631.4 5637.7 5503.9 5538.5 5546.1 5573.9 5632.7 5129.9 5292.5 5442.7 5447.2 5471.3 5316.8 5429.8 5553.5 5588.7 5598.8 4546.5 5452.2 5454.6 5463.9 5471.5 13:43:14 INFO - PROCESS | 1653 | 13:43:14 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:24629.31 Median:3456.44 stddev:39259.23 (1135.8%) stddev-sans-first:39328.29 13:43:14 INFO - PROCESS | 1653 | Values: 711.5 719.0 743.5 758.4 817.5 15085.2 16353.8 16375.1 16377.8 16458.8 14673.3 14803.9 14888.2 14899.3 14926.6 710.1 734.7 737.5 883.2 916.0 899.8 917.1 917.3 917.6 918.8 958.2 958.5 959.7 962.0 962.8 733.8 916.1 951.4 952.5 954.4 705.4 711.5 711.6 712.6 712.8 15194.2 15239.5 15257.6 15270.6 15303.9 13913.5 14011.3 14041.4 14139.6 14297.6 12186.2 12212.5 12258.4 12376.6 12441.8 3863.7 4551.7 4608.9 4701.6 4785.3 104420.0 106932.5 107587.5 108744.0 109323.0 11204.4 11221.8 11257.7 11260.7 11404.6 22620.9 22706.9 22731.8 22732.5 23015.5 7945.8 7978.4 8022.3 8074.0 8172.0 4389.5 4391.3 4393.1 4433.1 4581.2 3327.6 3438.9 3448.8 3456.0 3456.9 595.3 608.6 624.3 626.1 628.3 531.5 548.3 598.5 628.5 641.6 7226.0 7378.7 8934.6 8956.3 9235.9 570.6 590.5 623.9 639.8 641.8 4168.6 4352.3 4432.2 4652.4 4769.6 123382.0 151881.2 152427.0 154010.0 157849.2 121588.0 129275.0 131523.0 131831.3 135536.0 112349.4 113513.0 114007.0 114098.9 115883.0 8720.9 8739.4 8794.7 8812.4 9072.8 56803.7 57963.4 61365.7 61906.7 62572.7 62384.0 72225.0 85542.7 90185.5 95306.6 11538.3 13665.7 13790.2 13808.1 14287.8 15112.3 15251.5 15441.5 15882.8 16115.0 13985.6 14104.8 14276.4 14422.4 14739.8 12206.3 13753.9 14174.7 14318.4 14633.5 12028.2 12175.1 12448.1 12449.3 12453.4 11158.4 11559.8 11575.7 11693.2 11868.2 8497.6 8569.3 8900.8 10982.2 11317.2 13:43:14 INFO - PROCESS | 1653 | -------- Summary: end -------- 13:43:14 INFO - PROCESS | 1653 | 13:43:15 INFO - TEST-INFO | 1653: exit 0 13:43:15 INFO - TEST-OK | dromaeo_css | took 1117272ms 13:43:15 INFO - TEST-START | kraken 13:43:15 INFO - operating with platform_type : mac_ 13:43:15 INFO - Initialising browser for kraken test... 13:43:15 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpF5jgIf/profile http://localhost:49278/getInfo.html) 13:43:17 INFO - PROCESS | 1665 | JavaScript error: , line 0: uncaught exception: undefined 13:43:17 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 13:43:17 INFO - PROCESS | 1665 | colorDepth:24 13:43:17 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 13:43:17 INFO - PROCESS | 1665 | __metrics 13:43:17 INFO - PROCESS | 1665 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:43:17 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:43:18 INFO - TEST-INFO | 1665: exit 0 13:43:18 INFO - Browser initialized. 13:43:18 INFO - Running cycle 1/1 for kraken test... 13:43:18 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpF5jgIf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:43:30 INFO - PROCESS | 1666 | RSS: Main: 245387264 13:43:30 INFO - PROCESS | 1666 | 13:43:33 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 13:43:34 INFO - PROCESS | 1666 | RSS: Main: 324759552 13:43:34 INFO - PROCESS | 1666 | 13:43:36 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 13:43:37 INFO - PROCESS | 1666 | RSS: Main: 338722816 13:43:37 INFO - PROCESS | 1666 | 13:43:41 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 13:43:41 INFO - PROCESS | 1666 | RSS: Main: 426356736 13:43:41 INFO - PROCESS | 1666 | 13:43:43 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 13:43:44 INFO - PROCESS | 1666 | RSS: Main: 328646656 13:43:44 INFO - PROCESS | 1666 | 13:43:46 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:43:47 INFO - PROCESS | 1666 | RSS: Main: 333041664 13:43:47 INFO - PROCESS | 1666 | 13:43:49 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 13:43:50 INFO - PROCESS | 1666 | RSS: Main: 368930816 13:43:50 INFO - PROCESS | 1666 | 13:43:53 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 13:43:54 INFO - PROCESS | 1666 | RSS: Main: 371310592 13:43:54 INFO - PROCESS | 1666 | 13:43:57 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 13:43:57 INFO - PROCESS | 1666 | RSS: Main: 370864128 13:43:57 INFO - PROCESS | 1666 | 13:43:59 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:44:00 INFO - PROCESS | 1666 | RSS: Main: 349347840 13:44:00 INFO - PROCESS | 1666 | 13:44:02 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:44:02 INFO - PROCESS | 1666 | RSS: Main: 355979264 13:44:02 INFO - PROCESS | 1666 | 13:44:04 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:44:05 INFO - PROCESS | 1666 | RSS: Main: 354922496 13:44:05 INFO - PROCESS | 1666 | 13:44:08 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:44:09 INFO - PROCESS | 1666 | RSS: Main: 363929600 13:44:09 INFO - PROCESS | 1666 | 13:44:11 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:44:12 INFO - PROCESS | 1666 | RSS: Main: 365101056 13:44:12 INFO - PROCESS | 1666 | 13:44:14 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 13:44:14 INFO - PROCESS | 1666 | RSS: Main: 409071616 13:44:14 INFO - PROCESS | 1666 | 13:44:14 INFO - PROCESS | 1666 | __start_tp_report 13:44:14 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 13:44:14 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 13:44:14 INFO - PROCESS | 1666 | |i|pagename|runs| 13:44:14 INFO - PROCESS | 1666 | |0;ai-astar;102;113;113;114;114;115;114;114;113;114 13:44:14 INFO - PROCESS | 1666 | |1;audio-beat-detection;137;113;106;157;106;105;133;107;141;108 13:44:14 INFO - PROCESS | 1666 | |2;audio-dft;187;187;187;183;183;185;182;235;182;184 13:44:14 INFO - PROCESS | 1666 | |3;audio-fft;75;57;56;107;57;58;70;101;109;70 13:44:14 INFO - PROCESS | 1666 | |4;audio-oscillator;86;66;81;59;70;91;130;99;53;124 13:44:14 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;90;87;91;87;88;87;90;86;88;85 13:44:14 INFO - PROCESS | 1666 | |6;imaging-darkroom;96;96;97;96;96;97;96;97;149;149 13:44:14 INFO - PROCESS | 1666 | |7;imaging-desaturate;131;87;125;110;85;87;86;85;83;100 13:44:14 INFO - PROCESS | 1666 | |8;json-parse-financial;55;56;54;56;56;53;53;53;53;53 13:44:14 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;68;75;78;78;73;70;71;71;70;71 13:44:14 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;83;82;81;86;82;85;83;85;86;82 13:44:14 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;136;136;142;146;142;150;137;131;171;136 13:44:14 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;162;162;161;164;160;164;162;162;164;163 13:44:14 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;66;64;68;67;67;66;65;66;66;66 13:44:14 INFO - PROCESS | 1666 | __end_tp_report 13:44:14 INFO - PROCESS | 1666 | __start_cc_report 13:44:14 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,2131 13:44:14 INFO - PROCESS | 1666 | __end_cc_report 13:44:14 INFO - PROCESS | 1666 | __startTimestamp1469133854352__endTimestamp 13:44:14 INFO - PROCESS | 1666 | 13:44:14 INFO - PROCESS | 1666 | ------- Summary: start ------- 13:44:14 INFO - PROCESS | 1666 | Number of tests: 14 13:44:14 INFO - PROCESS | 1666 | 13:44:14 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:112.60 Median:114.00 stddev:3.78 (3.3%) stddev-sans-first:0.67 13:44:14 INFO - PROCESS | 1666 | Values: 102.0 113.0 113.0 114.0 114.0 115.0 114.0 114.0 113.0 114.0 13:44:14 INFO - PROCESS | 1666 | 13:44:14 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:121.30 Median:123.00 stddev:18.94 (15.4%) stddev-sans-first:19.22 13:44:14 INFO - PROCESS | 1666 | Values: 137.0 113.0 106.0 157.0 106.0 105.0 133.0 107.0 141.0 108.0 13:44:14 INFO - PROCESS | 1666 | 13:44:14 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:189.50 Median:186.00 stddev:16.11 (8.7%) stddev-sans-first:17.06 13:44:14 INFO - PROCESS | 1666 | Values: 187.0 187.0 187.0 183.0 183.0 185.0 182.0 235.0 182.0 184.0 13:44:14 INFO - PROCESS | 1666 | 13:44:14 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:76.00 Median:57.50 stddev:21.59 (37.5%) stddev-sans-first:22.89 13:44:14 INFO - PROCESS | 1666 | Values: 75.0 57.0 56.0 107.0 57.0 58.0 70.0 101.0 109.0 70.0 13:44:14 INFO - PROCESS | 1666 | 13:44:14 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:85.90 Median:75.50 stddev:25.98 (34.4%) stddev-sans-first:27.55 13:44:14 INFO - PROCESS | 1666 | Values: 86.0 66.0 81.0 59.0 70.0 91.0 130.0 99.0 53.0 124.0 13:44:14 INFO - PROCESS | 1666 | 13:44:14 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:88.00 stddev:1.91 (2.2%) stddev-sans-first:1.87 13:44:14 INFO - PROCESS | 1666 | Values: 90.0 87.0 91.0 87.0 88.0 87.0 90.0 86.0 88.0 85.0 13:44:14 INFO - PROCESS | 1666 | 13:44:14 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:106.90 Median:96.00 stddev:22.19 (23.1%) stddev-sans-first:23.19 13:44:14 INFO - PROCESS | 1666 | Values: 96.0 96.0 97.0 96.0 96.0 97.0 96.0 97.0 149.0 149.0 13:44:14 INFO - PROCESS | 1666 | 13:44:14 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:97.90 Median:85.00 stddev:18.00 (21.2%) stddev-sans-first:14.57 13:44:14 INFO - PROCESS | 1666 | Values: 131.0 87.0 125.0 110.0 85.0 87.0 86.0 85.0 83.0 100.0 13:44:14 INFO - PROCESS | 1666 | 13:44:14 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:54.20 Median:54.50 stddev:1.40 (2.6%) stddev-sans-first:1.45 13:44:14 INFO - PROCESS | 1666 | Values: 55.0 56.0 54.0 56.0 56.0 53.0 53.0 53.0 53.0 53.0 13:44:14 INFO - PROCESS | 1666 | 13:44:14 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:72.50 Median:72.00 stddev:3.44 (4.8%) stddev-sans-first:3.24 13:44:14 INFO - PROCESS | 1666 | Values: 68.0 75.0 78.0 78.0 73.0 70.0 71.0 71.0 70.0 71.0 13:44:14 INFO - PROCESS | 1666 | 13:44:14 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:84.00 stddev:1.84 (2.2%) stddev-sans-first:1.94 13:44:14 INFO - PROCESS | 1666 | Values: 83.0 82.0 81.0 86.0 82.0 85.0 83.0 85.0 86.0 82.0 13:44:14 INFO - PROCESS | 1666 | 13:44:14 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:142.70 Median:142.00 stddev:11.40 (8.0%) stddev-sans-first:11.83 13:44:14 INFO - PROCESS | 1666 | Values: 136.0 136.0 142.0 146.0 142.0 150.0 137.0 131.0 171.0 136.0 13:44:14 INFO - PROCESS | 1666 | 13:44:14 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.40 Median:162.50 stddev:1.35 (0.8%) stddev-sans-first:1.42 13:44:14 INFO - PROCESS | 1666 | Values: 162.0 162.0 161.0 164.0 160.0 164.0 162.0 162.0 164.0 163.0 13:44:14 INFO - PROCESS | 1666 | 13:44:14 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:1.10 (1.7%) stddev-sans-first:1.17 13:44:14 INFO - PROCESS | 1666 | Values: 66.0 64.0 68.0 67.0 67.0 66.0 65.0 66.0 66.0 66.0 13:44:14 INFO - PROCESS | 1666 | -------- Summary: end -------- 13:44:14 INFO - PROCESS | 1666 | 13:44:15 INFO - TEST-INFO | 1666: exit 0 13:44:15 INFO - TEST-OK | kraken | took 59654ms 13:44:15 INFO - SUITE-END | took 1176s 13:44:15 INFO - Completed test suite (00:19:37) 13:44:15 INFO - javascript benchmark 13:44:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3331.533585298058, 3380.7464440166996, 3382.188274814044, 3396.167938854981, 3446.138465383654, 5590.413006634306, 5680.06278963971, 5734.885302293952, 5783.624064435814, 5817.1565123057135, 5699.658020518761, 5740.913886291709, 5840.328362238344, 5866.677332746697, 5885.146653735206, 5137.3064636274, 5212.374515058198, 5237.554807841338, 5243.554297884684, 5342.626016178869, 5340.198970154488, 5340.732963351837, 5378.731063446831, 5391.245225668409, 5413.242146099549, 5124.718140502271, 5163.922541161885, 5165.922511162298, 5242.580593552507, 5252.606054545905, 5224.425313215544, 5235.424103348629, 5238.476152384731, 5258.789648414097, 5259.789608415659, 5136.512031357015, 5173.870653233662, 5177.48225177483, 5207.713575753332, 5220.791168353261, 5647.040003199425, 5710.229119068914, 5720.6281591696825, 5752.194692743019, 5756.453136951983, 6145.784897528565, 6240.875182496325, 6243.313235544087, 6247.031710084899, 6281.905771413432, 6129.759719006968, 6261.154744109533, 6262.091996660459, 6275.686215689243, 6288.654124023177, 5705.800296989669, 5766.798162064308, 5844.298684157886, 5928.881422371572, 5950.7917222896995, 5351.57187425005, 6200.131981522591, 6243.906341404882, 6253.812385628438, 6266.060090986389, 6101.420365065312, 6103.529224525481, 6106.175666284995, 6112.663803490762, 6123.020316749299, 6129.662868542273, 6203.565750397429, 6269.996800511987, 6295.118683384329, 6304.338044505353, 6148.1085242639565, 6203.193584834033, 6207.099970504251, 6209.130721698965, 6218.81343559694, 6191.659458729813, 6196.3803619638575, 6209.037599172136, 6209.472194863487, 6217.316095229521, 4856.587190088811, 4862.5380588844, 4919.4834542373255, 4936.234883593049, 4985.950140498621, 4434.3348497724955, 4440.289553671397, 4455.0, 4480.61914737251, 4547.931781023287, 5662.009148399014, 5708.457696518824, 5719.456651618173, 5740.397258287903, 5787.565932555075, 6113.810786210604, 6217.316095229521, 6241.812745617639, 6313.810585682437, 6315.0, 5676.517496012885, 5707.429257074259, 5718.79984200551, 5724.341700704473, 5737.512311453574, 5396.1366181410785, 5459.590530710133, 5472.315960504937, 5479.424660410679, 5490.478404551562, 6188.381161883775, 6229.906551401733, 6282.497400207881, 6285.025820997753, 6300.873982520416, 6294.24469063697, 6296.779612713533, 6304.936950630435, 6312.400321969406, 6317.7157027932935, 6202.7208775606105, 6269.373062693694, 6286.622802631857, 6290.779822706183, 6304.873902521832, 6101.368489499298, 6107.977837958671, 6130.172426722335, 6176.135341052346, 6189.195404597373, 6302.117703521419, 6304.117423560613, 6313.747450101944, 6325.620462772248, 6355.90466142999, 6133.693315334126, 6188.628682279078, 6195.349488303844, 6197.690115494297, 6217.751289948352, 5848.005839007464, 5902.881942361215, 6098.0086717533295, 6099.207103076749, 6169.136320914986, 6006.789762358297, 6055.609087387051, 6059.909101363395, 6066.803337684944, 6119.602225855483, 6125.111858780363, 6133.417325354084, 6154.292256390393, 6157.876842463036, 6182.938170618416, 5854.677992710442, 5933.821985340447, 5944.73248703818, 6039.577229593886, 6063.302720187235], "value": 5723.091457475335, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.5828703888734, 505.31529777151957, 527.3987654074355, 548.1531034551617, 552.1193696054792, 30586.776528938844, 42357.94105147373, 43713.0, 43753.343699844525, 46094.07811843761, 44578.88552786182, 44948.55051449485, 44970.55029449704, 45026.649200524036, 45081.0, 8707.216350528452, 9677.758056048602, 9859.0, 9867.555959981783, 9874.358166719141, 8680.8697869532, 11906.523739050428, 12103.757924841499, 12117.212381195193, 12400.0, 12105.939470302636, 12158.452869620844, 12205.145639805218, 12205.267683938991, 12206.267623942547, 8351.415400921938, 11852.585159519376, 11979.580714674988, 12071.698207544881, 12094.697632559213, 11645.59240426581, 12021.639350819401, 12024.038076953822, 12056.45745941426, 12061.396930153545, 42271.1545769086, 42479.93800155021, 42519.57480425187, 42558.574414255774, 42724.57275427237, 3502.702270307027, 3649.178934739689, 3656.341858465475, 3701.0747313171705, 4225.429567008446, 32885.342293154004, 32990.50514242288, 33369.6663033369, 33453.0, 33472.83263583667, 3249.675032496755, 3363.243270264171, 3420.3159368126476, 3425.811580363079, 3429.2455659754814, 488899.0, 491421.0, 492154.0, 493442.9999999964, 495993.0, 65236.0, 65472.67263663651, 65671.67164164149, 65723.74210063183, 65761.6711916447, 68886.0, 68924.31075689179, 69090.0, 69111.65444172747, 69207.30792692008, 17114.0, 17281.654366912593, 17288.913555432395, 17326.74009889853, 17420.216090275968, 12107.636770896886, 12329.383530823423, 13037.282949437871, 13211.867881321064, 13284.335783210994, 18245.908770456062, 18794.248230070643, 18832.34086806955, 18840.623187536174, 18866.0, 6210.658413787285, 7393.334599885929, 7432.145303290083, 7433.331000209899, 7542.622868856535, 937.9062093790567, 1000.7126589154592, 1084.2843723142687, 1115.7322242661864, 1137.4369687004985, 26007.219783406526, 27396.0, 30885.0, 31126.844365778026, 31349.43252837349, 932.2821427500786, 1003.9598416063276, 1048.0096308988086, 1127.4588197665166, 1181.3502573584494, 14944.775828362583, 15420.0, 15433.691326173639, 16366.0, 16422.32229650645, 677024.0, 680720.5963970148, 681569.0, 682618.0, 682917.0, 61607.691961540804, 61825.690871545354, 62034.37965620376, 62077.68961155165, 62147.37852621506, 1407.2963518240879, 1428.728541577097, 1470.0958910270322, 1472.3595236072342, 1473.0719646622558, 28452.715472845008, 29598.704012959595, 30619.0, 30680.0, 31416.842915785273, 53385.0, 53613.92772144613, 53632.0, 53663.73168134134, 53698.73150634222, 517309.0, 518937.0, 520733.0, 521530.0, 522070.0, 43005.0, 43985.78007109944, 44236.55763442325, 44897.32654010127, 44928.550714492434, 43161.78419107884, 49680.75798104932, 49864.0, 51069.0, 51722.22416663678, 40934.38598421086, 41779.9555011115, 42269.440095430786, 42276.57723422726, 42342.51801187067, 40483.98790030273, 41202.0, 41300.0, 42374.0, 43168.35247471353, 38793.80603096967, 39383.01542461461, 39506.60493395029, 40144.397834033094, 40190.39714404345, 32521.837390812896, 34033.31933361269, 35577.822110890316, 36591.26817463689, 36654.63345366512, 26004.21987340383, 26059.670170853842, 26169.0, 26368.60129596515, 26920.0], "value": 21389.70685151658, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.26499423730326, 374.23469005882964, 374.9531308586425, 380.12191836856863, 406.0498433665223, 15535.611609709764, 16266.0, 20477.0, 20697.0, 20830.89584552079, 20458.363330933527, 20634.8650824205, 21039.0, 21047.368578942616, 21480.140794368206, 4892.510748925097, 5369.328833895763, 5436.945630543693, 5554.750036248358, 5621.297337832771, 3940.5271367435803, 5513.3108361454815, 5601.411851755568, 5604.243427137346, 5616.747246373923, 5899.115132730082, 6114.113453549233, 6175.783847565336, 6219.875602487948, 6222.719977600997, 6199.907001394983, 6202.751889924398, 6291.653959032252, 6852.862942741117, 6934.341237582422, 6082.847928801771, 6088.208532890696, 6092.573519853612, 6108.938910610881, 6112.419320164578, 13555.186688798703, 13604.86395136046, 13731.862681373159, 13925.885929125747, 15637.765433518507, 1902.2105826082159, 2223.49971256466, 2306.5156317173414, 2338.777816107467, 2783.956016493815, 10220.89779102207, 10635.255532112755, 12598.244105353708, 15789.0, 16545.420910268203, 1875.7373967644542, 1917.3960202536157, 1943.2227109156308, 1945.7956914523913, 1958.0, 107675.0, 119293.0, 121228.0, 125468.37265813704, 129633.0, 10898.836517452244, 11569.363684997406, 11634.6509604712, 11647.59233426826, 11890.28658280506, 12168.2699038058, 14726.042807217496, 14736.410543578137, 14737.410503579951, 14744.083269174938, 8472.406931514857, 8759.204363105548, 8792.824143517093, 8891.822163556693, 8930.374873758774, 8869.0, 8882.734902599796, 8907.198352148338, 9077.909220907839, 9079.45523268606, 4547.431571053618, 4612.76936153191, 4616.3998680172035, 4630.398048253773, 4713.6936099153445, 1504.6840163565773, 1839.6596629623562, 1894.895780732073, 1921.9423417297503, 1939.9806001940083, 843.4138273899704, 900.1178844732068, 913.6345461815328, 924.1036194890945, 945.3146468810057, 21102.155913763585, 22129.114835406403, 22818.0, 22847.543049138923, 22873.656895146585, 913.2830727878717, 940.6989763275823, 947.9004704506066, 981.7790997025755, 1032.7624646331235, 9766.267529935283, 11142.052925501423, 11807.763844723057, 11852.822207666892, 11873.050155987501, 572480.0, 576247.0, 585291.0, 603589.0, 603875.0, 75176.0, 94813.0, 117857.23214151623, 129585.35207324092, 141037.0, 875.465965760898, 939.2901479232328, 979.7256768104942, 996.820572296979, 999.5352161245056, 16350.427735029218, 21019.054142563928, 21200.469988250417, 21257.04343304524, 21336.03987820583, 58756.70621646864, 58837.0, 60538.69730651495, 60724.481887951384, 61235.69382153061, 83855.580722096, 84754.0, 86994.0, 89748.55125724591, 90609.0, 5487.314085739283, 5513.365962914316, 7650.693972241048, 8230.341572674291, 8453.196946290092, 13337.599872003855, 13376.398062087035, 13630.522931697344, 13863.445462181804, 13867.029307948347, 11319.886801131883, 11527.942360288145, 11644.53421863116, 11703.531858725897, 11793.41032948332, 11441.027512661183, 11459.770804584028, 11752.23610465317, 11875.524979000744, 11903.226290291102, 11548.480318385526, 11695.941520292345, 12115.878841211475, 12129.21160124589, 12208.572699955459, 7610.581418022062, 8050.476719013245, 8317.875231871647, 8382.413231073766, 8401.0, 9250.0, 11687.474063666985, 11959.581414650445, 11959.701007474883, 11961.282323060645], "value": 9603.384857092335, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1456.934437950292, 1632.6326576520285, 1783.6076063266084, 1963.469863136954, 2003.0285311623868, 8399.874001889977, 8902.020777714446, 8919.420237684546, 8928.19646231839, 8936.59785309661, 7061.682224299906, 7694.384449244061, 7967.0, 8678.566071696405, 8717.956410217956, 2326.1393284484766, 2375.7861792438675, 2383.0825132324007, 2384.332386931662, 2387.0, 2337.4857531343164, 2350.306659535441, 2371.5849726297915, 2379.3813608461837, 2385.8807059647033, 2337.836351455399, 2355.4346956730433, 2367.1951536477595, 2378.4053986503372, 2388.164142550111, 2327.2669109230537, 2334.054707843318, 2339.274824804299, 2346.5189636124555, 2360.563295790267, 2336.205690065369, 2346.835721499496, 2350.7061617297836, 2353.6116540770754, 2361.409647588103, 8830.20528152463, 8868.689595864114, 8951.597178127, 8991.0, 8994.910050899472, 5169.974150129263, 5343.786248550054, 5344.839654810362, 5350.170723537819, 5368.463153684639, 3514.4025515662397, 3571.3035957988204, 3577.6422357764277, 3581.176329444216, 3585.766925149857, 1001.8345538968159, 1021.4126877045728, 1054.915606751458, 1087.6954452753241, 1104.950277237518, 11167.888321116847, 11361.261518001304, 11410.714732131763, 11413.54345826166, 11427.428628568538, 4461.286194208976, 4535.795889184995, 4676.368690226844, 4740.1230772307235, 4760.880977975578, 8243.845861579384, 9546.761330966781, 9705.805883882284, 9770.022997700315, 9771.21830253578, 5258.3689957204615, 5276.736163191825, 5276.788928442819, 5277.973610131925, 5278.841634750964, 4314.374415709704, 4491.258942274553, 4670.5329467053025, 4795.736234507136, 4837.298591704183, 3834.884953451401, 3839.0786211309087, 3969.4641223435046, 3998.8600398985895, 4031.838726450968, 1256.2839181666363, 1256.673264951101, 1259.9055070869722, 1263.7914744067125, 1288.0597164070282, 873.8776571280026, 953.1993125774383, 968.2592816495387, 971.7221853262937, 1021.0810270756378, 6032.698365081728, 6072.635641861502, 6160.507159427235, 6188.257409110935, 6202.255729312511, 374.4289957814355, 449.5302408982621, 450.28404836310426, 454.6885383512305, 461.63069544364373, 4009.4787677601726, 4025.798710064485, 4069.1251380953236, 4142.627163555234, 4175.0, 10147.34042287249, 10649.041586257274, 10655.520501577448, 10703.571857125784, 10719.517621707042, 10651.09465695409, 10672.786544269227, 10728.141748660088, 10731.195160363004, 10734.033936945712, 7734.4585878989055, 8104.230098140785, 8127.878081828777, 8183.79540511492, 8219.876701849476, 6030.3668114847305, 6076.848078797889, 6104.359042300494, 6249.093881387264, 6282.937170628235, 9979.850302245617, 10125.038121378457, 10155.984401559785, 10158.08577228053, 10241.897581024094, 9827.459489728133, 9834.0, 10139.493025348556, 10280.126189273697, 10327.586896524055, 6645.1361323027695, 7037.049998250171, 7251.8187045324285, 7388.371988381048, 7438.033055702724, 8307.169283071644, 8501.447405918596, 8512.829743405222, 8534.231919127309, 8543.231109200202, 7368.263173682589, 7425.405967522477, 7559.20628334017, 7696.692132314644, 7723.806904827199, 7315.743948961761, 7382.52013619113, 7497.475176737576, 7542.4720269582795, 7635.160132385544, 7017.7192912282935, 7147.035150254649, 7155.606441645759, 7189.137303523611, 7196.676149573386, 4852.466228714909, 4985.850424487271, 5125.282460455464, 5231.136862417658, 5260.842174734764, 5018.0, 5051.974740126423, 5207.453217412269, 5227.111391063452, 5234.450382709761], "value": 4518.359598892838, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [270.69276371318546, 299.96850330715273, 301.8641611274927, 312.0529193896523, 321.96136463624356, 4579.862604121873, 4978.2532620107, 5224.869378265541, 5300.0, 5320.494553017466, 4561.863144105674, 4793.08931303053, 5098.898022039557, 5134.717590532519, 5147.790269286724, 2992.9102126936227, 3290.8190049547266, 3933.4069701770686, 3967.0, 4025.879223623296, 3338.933221335572, 4027.5166979962446, 4031.6976226782963, 4040.878773636781, 4090.6522945549664, 3742.569604495477, 3957.366821308591, 3991.6607088397377, 4007.83968641254, 4017.312728653958, 3997.2205419943125, 4007.9599204007877, 4008.518977722692, 4020.336644453647, 4025.5571887092397, 3441.483777433405, 3458.9654103459147, 3473.218525831693, 3473.39215637265, 3484.860605575774, 5082.669626474304, 5113.846584602468, 5131.923021154686, 5133.794648214067, 5178.0, 4034.6570541504007, 4248.7693960670895, 4560.065186636764, 4570.428696412949, 4643.744594047326, 4660.720356778605, 5458.918116228259, 5547.77808887644, 5604.8318550443555, 5607.55139588836, 3315.6187038490643, 3722.088088418328, 3778.8110594470168, 3805.0487378155462, 3831.927060423086, 5317.867053323621, 5481.561475081984, 5801.3038435388025, 5831.017454065595, 5861.20873682056, 3131.4519959007084, 3378.8986330410125, 3863.613638636114, 3878.631530004645, 3884.834549635098, 3464.566929133858, 3666.6433419634373, 3748.156664750409, 3923.588023257574, 3938.6849052075763, 3905.8047097645003, 4336.804843782037, 4567.246404343313, 4567.65742569302, 4569.405977222939, 5562.86092847674, 5601.467860553241, 5601.747921343549, 5618.37067250501, 5652.406497317723, 1367.282176857158, 1387.8681525255085, 1398.7970345503063, 1401.7546929287334, 1410.1820943852747, 871.3900269811157, 906.8549032154824, 924.6763632728491, 946.5267366316841, 955.8900726416551, 5018.0214858103045, 5025.673331233458, 5041.395032596039, 5044.445111037783, 5083.500367391628, 5317.042932272228, 5571.582131340085, 5638.210650508932, 5702.258706368146, 5716.1997320375185, 4800.759962001886, 5082.923756143661, 5127.717975511308, 5193.584513238932, 5263.578913686896, 2489.962650560243, 2992.0, 2994.9550756738668, 2995.5806187133817, 3001.1446737679657, 5522.337319521683, 5822.679752613562, 5857.384974577693, 5861.325947516005, 5870.739765190432, 5314.9901518712095, 5534.374984385234, 5713.342965558931, 5854.765809367577, 5974.595969947059, 5009.298698182258, 5315.309009828775, 5399.406065332811, 5843.0, 5934.37689042653, 5408.1071624930955, 5697.772089116389, 5725.484706376446, 5816.952948469316, 5873.65053978412, 5647.887042259214, 5666.2350582670815, 5668.773249069991, 5669.829905102853, 5686.57350698691, 5478.123500239943, 5576.553875689854, 5895.7052147393315, 5928.881422371443, 5945.494632956247, 3386.797686821143, 3624.7100231981904, 3788.962110378861, 3822.5795162532654, 3872.3997780344093, 4821.686590371614, 5655.709437677747, 5709.628874123168, 5737.684427356536, 5785.0, 5128.564072053768, 5260.0531904257605, 5352.625316227826, 5631.380548139619, 5637.689927054052, 5503.889922201613, 5538.529225015919, 5546.084895992116, 5573.860653483695, 5632.746526406239, 5129.8717532062, 5292.470752924677, 5442.673439593637, 5447.210154527492, 5471.2613797138465, 5316.840494785162, 5429.782808687608, 5553.527950124285, 5588.692621905671, 5598.804041858515, 4546.4544254689645, 5452.209429632601, 5454.59090568201, 5463.945360546502, 5471.480209380103], "value": 4067.963306928556, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.5090587494628, 719.0329007484933, 743.4944237918216, 758.3781299334548, 817.4563914996528, 15085.170315632637, 16353.83646163538, 16375.099369534671, 16377.754333685005, 16458.75311870323, 14673.339699713511, 14803.925980370082, 14888.181150036744, 14899.255037248122, 14926.626834329147, 710.105267363121, 734.6583838515089, 737.5464089584893, 883.2051153961422, 916.0107084348887, 899.8380291547519, 917.1195652173888, 917.2891009467683, 917.6467060181844, 918.8392031394479, 958.228625956105, 958.5399008475936, 959.6785076999179, 961.966331178409, 962.8170647576973, 733.8348871503869, 916.1434059154711, 951.4148798488949, 952.5237381309346, 954.398728800858, 705.4109818301724, 711.5161690050763, 711.5872793779596, 712.6436781609195, 712.8396110875063, 15194.164320962343, 15239.542813715607, 15257.6185595361, 15270.618234544225, 15303.923480382638, 13913.513027044004, 14011.299435028208, 14041.368138433692, 14139.575812725634, 14297.64255893611, 12186.2078964867, 12212.511499540007, 12258.387080645933, 12376.62870113898, 12441.813372799415, 3863.651021228268, 4551.68138230324, 4608.907821843578, 4701.623870090419, 4785.282207668877, 104420.0, 106932.4653376736, 107587.46206268997, 108744.0, 109323.0, 11204.439778011067, 11221.831672524919, 11257.662270131908, 11260.718482038015, 11404.600838970597, 22620.886895565418, 22706.886465567568, 22731.772682273295, 22732.545349093256, 23015.53968920612, 7945.801354966172, 7978.441509094308, 8022.318102961197, 8074.0, 8172.0, 4389.517153113155, 4391.297392417262, 4393.121375724868, 4433.135538569948, 4581.198290299184, 3327.600687917417, 3438.9312213755584, 3448.758446959143, 3456.0, 3456.879009234665, 595.3213336796056, 608.6165715599144, 624.2883113250898, 626.1140486211959, 628.2680677011322, 531.5402177116844, 548.347466514846, 598.494272339872, 628.5097623853402, 641.6374748267214, 7226.0, 7378.741744038933, 8934.597943092576, 8956.283497320199, 9235.861462078074, 570.647446253262, 590.4715279824619, 623.8565130020121, 639.8240483866881, 641.788209890735, 4168.583141685868, 4352.347147927836, 4432.202203603382, 4652.441706995114, 4769.642276829251, 123382.0, 151881.24059379855, 152427.0, 154010.0, 157849.2107539455, 121588.0, 129275.0, 131523.0, 131831.34084329518, 135536.0, 112349.4382528082, 113513.0, 114007.0, 114098.85901140883, 115883.0, 8720.91279087201, 8739.38824282294, 8794.736157915273, 8812.427192232484, 9072.773180670272, 56803.71598142148, 57963.4203657958, 61365.69317153386, 61906.69046654918, 62572.687136564025, 62384.0, 72225.0, 85542.73487181577, 90185.54907225422, 95306.57040144542, 11538.307701537935, 13665.726685466434, 13790.241536715577, 13808.102473339603, 14287.785683214552, 15112.319945602694, 15251.466198682992, 15441.5367538974, 15882.761758573863, 16115.0, 13985.58043258704, 14104.788428173792, 14276.357563909442, 14422.350994205077, 14739.787966158363, 12206.32865192423, 13753.931230343784, 14174.716505670036, 14318.427262909783, 14633.487827925972, 12028.218165819193, 12175.14773965814, 12448.128630996105, 12449.315287659265, 12453.377331133226, 11158.427635497384, 11559.76880462403, 11575.71060723455, 11693.181477296508, 11868.169228153947, 8497.627098387162, 8569.271611913056, 8900.759651739701, 10982.231243813176, 11317.151213659174], "value": 7822.888419919661, "unit": "score"}], "name": "dromaeo_css", "value": 7435.993966730205, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 113.0, 114.0, 114.0, 115.0, 114.0, 114.0, 113.0, 114.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 113.0, 106.0, 157.0, 106.0, 105.0, 133.0, 107.0, 141.0, 108.0], "value": 121.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 187.0, 183.0, 183.0, 185.0, 182.0, 235.0, 182.0, 184.0], "value": 189.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 57.0, 56.0, 107.0, 57.0, 58.0, 70.0, 101.0, 109.0, 70.0], "value": 76.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 66.0, 81.0, 59.0, 70.0, 91.0, 130.0, 99.0, 53.0, 124.0], "value": 85.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 87.0, 88.0, 87.0, 90.0, 86.0, 88.0, 85.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 96.0, 96.0, 97.0, 96.0, 97.0, 149.0, 149.0], "value": 106.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 87.0, 125.0, 110.0, 85.0, 87.0, 86.0, 85.0, 83.0, 100.0], "value": 97.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 54.0, 56.0, 56.0, 53.0, 53.0, 53.0, 53.0, 53.0], "value": 54.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 75.0, 78.0, 78.0, 73.0, 70.0, 71.0, 71.0, 70.0, 71.0], "value": 72.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 81.0, 86.0, 82.0, 85.0, 83.0, 85.0, 86.0, 82.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 136.0, 142.0, 146.0, 142.0, 150.0, 137.0, 131.0, 171.0, 136.0], "value": 142.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 164.0, 160.0, 164.0, 162.0, 162.0, 164.0, 163.0], "value": 162.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 68.0, 67.0, 67.0, 66.0, 65.0, 66.0, 66.0, 66.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1459.3999999999999, "alertThreshold": 2.0}]} 13:44:15 INFO - Return code: 0 13:44:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:44:15 INFO - # TBPL SUCCESS # 13:44:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:44:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:44:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:44:15 INFO - 2016-07-21 13:44:15.664 screenresolution[1670:9755] starting screenresolution argv=screenresolution get 13:44:15 INFO - 2016-07-21 13:44:15.667 screenresolution[1670:9755] Display 0: 1600x1200x32@60 13:44:15 INFO - 2016-07-21 13:44:15.673 screenresolution[1671:9758] starting screenresolution argv=screenresolution list 13:44:15 INFO - Available Modes on Display 0 13:44:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:44:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:44:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:44:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:44:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:44:15 INFO - Intel Iris: 13:44:15 INFO - Chipset Model: Intel Iris 13:44:15 INFO - Type: GPU 13:44:15 INFO - Bus: Built-In 13:44:15 INFO - VRAM (Dynamic, Max): 1536 MB 13:44:15 INFO - Vendor: Intel (0x8086) 13:44:15 INFO - Device ID: 0x0a2e 13:44:15 INFO - Revision ID: 0x0009 13:44:15 INFO - Displays: 13:44:15 INFO - Display: 13:44:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:44:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:44:15 INFO - Display Serial Number: 1600x1200 60 13:44:15 INFO - Main Display: Yes 13:44:15 INFO - Mirror: Off 13:44:15 INFO - Online: Yes 13:44:15 INFO - Rotation: Supported 13:44:15 INFO - Return code: 0 13:44:15 INFO - Running post-action listener: _resource_record_post_action 13:44:15 INFO - [mozharness: 2016-07-21 20:44:15.912710Z] Finished run-tests step (success) 13:44:15 INFO - Running post-run listener: _resource_record_post_run 13:44:15 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 36877312; Write bytes: 704722432; Read time: 243; Write time: 1978 13:44:15 INFO - TinderboxPrint: CPU usage
53.4% 13:44:15 INFO - TinderboxPrint: I/O read bytes / time
36,877,312 / 243 13:44:15 INFO - TinderboxPrint: I/O write bytes / time
704,722,432 / 1,978 13:44:15 INFO - TinderboxPrint: CPU idle
2,258.0 (46.6%) 13:44:15 INFO - TinderboxPrint: CPU system
965.2 (19.9%) 13:44:15 INFO - TinderboxPrint: CPU user
1,626.4 (33.5%) 13:44:15 INFO - TinderboxPrint: Swap in / out
444,555,264 / 0 13:44:15 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 388917760; Write bytes: 382150656; Read time: 28970; Write time: 839 13:44:15 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 35922944; Write bytes: 295333376; Read time: 231; Write time: 1076 13:44:16 INFO - Running post-run listener: _upload_blobber_files 13:44:16 INFO - Blob upload gear active. 13:44:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:44:16 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 13:44:16 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:44:16 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:44:16 INFO - (blobuploader) - INFO - Open directory for files ... 13:44:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:44:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:44:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:44:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:44:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:44:18 INFO - (blobuploader) - INFO - Done attempting. 13:44:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:44:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:44:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:44:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:44:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:44:19 INFO - (blobuploader) - INFO - Done attempting. 13:44:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:44:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:44:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:44:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:44:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:44:24 INFO - (blobuploader) - INFO - Done attempting. 13:44:24 INFO - (blobuploader) - INFO - Iteration through files over. 13:44:24 INFO - Return code: 0 13:44:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:44:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:44:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/710eb51e0ee4a1110164de725c1612f14342e5e1dd2453345ed5200f1e9aa9575fe3611e2e82d6bd3238f271b61b741e5e8a8553d7f392de3a973c80b06204c2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a66b8b9f8f3b7568c44d7fe6fa8e0fa63a635f6bebbfeab4acc1a1084978ae1a378745f6031d8ded6fad5b968614c0c5f9e10c4dba0f1dce2a602b5ac739ef11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8d3c558e2eb17f1fb0ce546406f7a51f0b071a56dec721503352dadda08a4e17458f6b688f93104d002e270d6da81ac7d621c6a7f480ad644804c82678cf484"} 13:44:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:44:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:44:24 INFO - Contents: 13:44:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/710eb51e0ee4a1110164de725c1612f14342e5e1dd2453345ed5200f1e9aa9575fe3611e2e82d6bd3238f271b61b741e5e8a8553d7f392de3a973c80b06204c2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a66b8b9f8f3b7568c44d7fe6fa8e0fa63a635f6bebbfeab4acc1a1084978ae1a378745f6031d8ded6fad5b968614c0c5f9e10c4dba0f1dce2a602b5ac739ef11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8d3c558e2eb17f1fb0ce546406f7a51f0b071a56dec721503352dadda08a4e17458f6b688f93104d002e270d6da81ac7d621c6a7f480ad644804c82678cf484"} 13:44:24 INFO - Running post-run listener: copy_logs_to_upload_dir 13:44:24 INFO - Copying logs to upload dir... 13:44:24 INFO - mkdir: /builds/slave/test/build/upload/logs 13:44:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.548924 ========= master_lag: 0.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-07-21 13:44:24.631393) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 13:44:24.634479) ========= 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.FjVJCUSRUS/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.rHS1cAAuzD/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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/Fx-Team/sha512/710eb51e0ee4a1110164de725c1612f14342e5e1dd2453345ed5200f1e9aa9575fe3611e2e82d6bd3238f271b61b741e5e8a8553d7f392de3a973c80b06204c2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a66b8b9f8f3b7568c44d7fe6fa8e0fa63a635f6bebbfeab4acc1a1084978ae1a378745f6031d8ded6fad5b968614c0c5f9e10c4dba0f1dce2a602b5ac739ef11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8d3c558e2eb17f1fb0ce546406f7a51f0b071a56dec721503352dadda08a4e17458f6b688f93104d002e270d6da81ac7d621c6a7f480ad644804c82678cf484"} build_url:https://queue.taskcluster.net/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010916 build_url: 'https://queue.taskcluster.net/v1/task/XDqxjlgeRNW-t2TBlbmPuw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/710eb51e0ee4a1110164de725c1612f14342e5e1dd2453345ed5200f1e9aa9575fe3611e2e82d6bd3238f271b61b741e5e8a8553d7f392de3a973c80b06204c2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a66b8b9f8f3b7568c44d7fe6fa8e0fa63a635f6bebbfeab4acc1a1084978ae1a378745f6031d8ded6fad5b968614c0c5f9e10c4dba0f1dce2a602b5ac739ef11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c8d3c558e2eb17f1fb0ce546406f7a51f0b071a56dec721503352dadda08a4e17458f6b688f93104d002e270d6da81ac7d621c6a7f480ad644804c82678cf484"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 13:44:24.668328) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 13:44:24.668684) ========= 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.FjVJCUSRUS/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.rHS1cAAuzD/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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.004993 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 13:44:24.718336) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 13:44:24.718953) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 13:44:24.719669) ========= ========= Total master_lag: 3.03 =========