builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0012 starttime: 1468514568.38 results: success (0) buildid: 20160714064843 builduid: e85601e9cfed4acda909725d98bdc68d revision: 6b9dae7e278dcd38d0b1c221ca9420931dfac7fd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-14 09:42:48.377973) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-14 09:42:48.378420) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-14 09:42:48.378695) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-14 09:42:48.427667) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 09:42:48.427944) ========= 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.BTLrpfUGr5/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.Ad38fgbDsf/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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-14 09:42:48-- 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% 141M=0s 2016-07-14 09:42:48 (141 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124336 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 09:42:48.575165) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 09:42:48.575487) ========= 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.BTLrpfUGr5/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.Ad38fgbDsf/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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.020426 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 09:42:48.623416) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-07-14 09:42:48.623734) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 6b9dae7e278dcd38d0b1c221ca9420931dfac7fd --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 6b9dae7e278dcd38d0b1c221ca9420931dfac7fd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BTLrpfUGr5/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.Ad38fgbDsf/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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-14 09:42:48,698 truncating revision to first 12 chars 2016-07-14 09:42:48,698 Setting DEBUG logging. 2016-07-14 09:42:48,699 attempt 1/10 2016-07-14 09:42:48,699 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/6b9dae7e278d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-14 09:42:49,329 unpacking tar archive at: fx-team-6b9dae7e278d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.889236 ========= master_lag: 2.15 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-07-14 09:42:51.662715) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-14 09:42:51.663017) ========= script_repo_revision: 6b9dae7e278dcd38d0b1c221ca9420931dfac7fd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-14 09:42:51.663406) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-14 09:42:51.663711) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-14 09:42:51.692583) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-14 09:42:51.692895) ========= /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.BTLrpfUGr5/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.Ad38fgbDsf/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:42:51 INFO - MultiFileLogger online at 20160714 09:42:51 in /builds/slave/test 09:42:51 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 09:42:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:42:51 INFO - {'append_to_log': False, 09:42:51 INFO - 'base_work_dir': '/builds/slave/test', 09:42:51 INFO - 'blob_upload_branch': 'Fx-Team', 09:42:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:42:51 INFO - 'branch': 'Fx-Team', 09:42:51 INFO - 'buildbot_json_path': 'buildprops.json', 09:42:51 INFO - 'config_files': ('talos/mac_config.py',), 09:42:51 INFO - 'default_actions': ('clobber', 09:42:51 INFO - 'read-buildbot-config', 09:42:51 INFO - 'download-and-extract', 09:42:51 INFO - 'populate-webroot', 09:42:51 INFO - 'create-virtualenv', 09:42:51 INFO - 'install', 09:42:51 INFO - 'run-tests'), 09:42:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:42:51 INFO - 'download_minidump_stackwalk': True, 09:42:51 INFO - 'download_symbols': 'ondemand', 09:42:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:42:51 INFO - 'tooltool.py': '/tools/tooltool.py', 09:42:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:42:51 INFO - '/tools/misc-python/virtualenv.py')}, 09:42:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:42:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:42:51 INFO - 'installer_path': 'installer.exe', 09:42:51 INFO - 'log_level': 'info', 09:42:51 INFO - 'log_name': 'talos', 09:42:51 INFO - 'log_to_console': True, 09:42:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:42:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:42:51 INFO - 'opt_config_files': (), 09:42:51 INFO - 'pip_index': False, 09:42:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:42:51 INFO - 'cmd': ('bash', 09:42:51 INFO - '-c', 09:42:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:42:51 INFO - 'enabled': True, 09:42:51 INFO - 'halt_on_failure': False, 09:42:51 INFO - 'name': 'check_screen_resolution'},), 09:42:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:42:51 INFO - 'cmd': ('bash', 09:42:51 INFO - '-c', 09:42:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:42:51 INFO - 'enabled': True, 09:42:51 INFO - 'halt_on_failure': False, 09:42:51 INFO - 'name': 'check_screen_resolution'},), 09:42:51 INFO - 'run_cmd_checks_enabled': True, 09:42:51 INFO - 'sps_profile': False, 09:42:51 INFO - 'sps_profile_interval': 0, 09:42:51 INFO - 'suite': 'dromaeojs', 09:42:51 INFO - 'system_bits': '32', 09:42:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:42:51 INFO - 'title': 't-yosemite-r7-0012', 09:42:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:42:51 INFO - 'use_talos_json': True, 09:42:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:42:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:42:51 INFO - 'work_dir': 'build'} 09:42:51 INFO - ##### 09:42:51 INFO - ##### Running clobber step. 09:42:51 INFO - ##### 09:42:51 INFO - Running pre-action listener: _resource_record_pre_action 09:42:51 INFO - Running main action method: clobber 09:42:51 INFO - rmtree: /builds/slave/test/build 09:42:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:42:53 INFO - Running post-action listener: _resource_record_post_action 09:42:53 INFO - ##### 09:42:53 INFO - ##### Finished clobber step (success) 09:42:53 INFO - ##### 09:42:53 INFO - ##### 09:42:53 INFO - ##### Running read-buildbot-config step. 09:42:53 INFO - ##### 09:42:53 INFO - Running pre-action listener: _resource_record_pre_action 09:42:53 INFO - Running main action method: read_buildbot_config 09:42:53 INFO - Using buildbot properties: 09:42:53 INFO - { 09:42:53 INFO - "project": "", 09:42:53 INFO - "product": "firefox", 09:42:53 INFO - "script_repo_revision": "production", 09:42:53 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 09:42:53 INFO - "repository": "", 09:42:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 09:42:53 INFO - "buildid": "20160714064843", 09:42:53 INFO - "pgo_build": "False", 09:42:53 INFO - "basedir": "/builds/slave/test", 09:42:53 INFO - "buildnumber": 799, 09:42:53 INFO - "slavename": "t-yosemite-r7-0012", 09:42:53 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:42:53 INFO - "platform": "yosemite_r7", 09:42:53 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 09:42:53 INFO - "branch": "fx-team", 09:42:53 INFO - "repo_path": "integration/fx-team", 09:42:53 INFO - "revision": "6b9dae7e278dcd38d0b1c221ca9420931dfac7fd", 09:42:53 INFO - "stage_platform": "macosx64", 09:42:53 INFO - "builduid": "e85601e9cfed4acda909725d98bdc68d", 09:42:53 INFO - "slavebuilddir": "test" 09:42:53 INFO - } 09:42:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 09:42:53 INFO - Running post-action listener: _resource_record_post_action 09:42:53 INFO - ##### 09:42:53 INFO - ##### Finished read-buildbot-config step (success) 09:42:53 INFO - ##### 09:42:53 INFO - ##### 09:42:53 INFO - ##### Running download-and-extract step. 09:42:53 INFO - ##### 09:42:53 INFO - Running pre-action listener: _resource_record_pre_action 09:42:53 INFO - Running main action method: download_and_extract 09:42:53 INFO - mkdir: /builds/slave/test/build/tests 09:42:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:53 INFO - https://queue.taskcluster.net/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:42:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:42:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 09:42:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:42:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 09:42:54 INFO - Downloaded 1199 bytes. 09:42:54 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 09:42:54 INFO - Using the following test package requirements: 09:42:54 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:42:54 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:42:54 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 09:42:54 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:42:54 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 09:42:54 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:42:54 INFO - u'jsshell-mac.zip'], 09:42:54 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:42:54 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 09:42:54 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 09:42:54 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:42:54 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 09:42:54 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:42:54 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 09:42:54 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:42:54 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 09:42:54 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 09:42:54 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 09:42:54 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:42:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:54 INFO - https://queue.taskcluster.net/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:42:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:42:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:42:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:42:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:42:56 INFO - Downloaded 18827918 bytes. 09:42:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:42:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:42:59 INFO - Return code: 0 09:42:59 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:42:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:42:59 INFO - https://queue.taskcluster.net/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:42:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:42:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:42:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 09:42:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:43:00 INFO - Downloaded 18827918 bytes. 09:43:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:43:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:43:03 INFO - Return code: 0 09:43:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:03 INFO - https://queue.taskcluster.net/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:43:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:43:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:43:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 09:43:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:43:05 INFO - Downloaded 14403317 bytes. 09:43:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:43:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:43:05 INFO - Return code: 0 09:43:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:05 INFO - https://queue.taskcluster.net/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 09:43:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:43:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:43:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 09:43:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 09:43:08 INFO - Downloaded 112741590 bytes. 09:43:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:43:08 INFO - mkdir: /builds/slave/test/properties 09:43:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:43:08 INFO - Writing to file /builds/slave/test/properties/build_url 09:43:08 INFO - Contents: 09:43:08 INFO - build_url:https://queue.taskcluster.net/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 09:43:09 INFO - Running post-action listener: _resource_record_post_action 09:43:09 INFO - Running post-action listener: set_extra_try_arguments 09:43:09 INFO - ##### 09:43:09 INFO - ##### Finished download-and-extract step (success) 09:43:09 INFO - ##### 09:43:09 INFO - ##### 09:43:09 INFO - ##### Running populate-webroot step. 09:43:09 INFO - ##### 09:43:09 INFO - Running pre-action listener: _resource_record_pre_action 09:43:09 INFO - Running main action method: populate_webroot 09:43:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:43:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:43:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:43:09 INFO - '--tppagecycles', 09:43:09 INFO - '7'], 09:43:09 INFO - 'tests': ['tsvgm']}}, 09:43:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:43:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:43:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:43:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:43:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:43:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:43:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:09 INFO - 'talos_options': ['--disable-e10s'], 09:43:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:43:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:43:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:09 INFO - 'talos_options': ['--disable-e10s'], 09:43:09 INFO - 'tests': ['damp', 'tps']}, 09:43:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:09 INFO - 'tests': ['damp', 'tps']}, 09:43:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:43:09 INFO - 'tests': ['dromaeo_dom']}, 09:43:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:43:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:43:09 INFO - 'tests': ['basic_compositor_video']}, 09:43:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:43:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:43:09 INFO - 'tests': ['a11yr', 09:43:09 INFO - 'ts_paint', 09:43:09 INFO - 'tpaint', 09:43:09 INFO - 'sessionrestore', 09:43:09 INFO - 'sessionrestore_no_auto_restore', 09:43:09 INFO - 'tabpaint']}, 09:43:09 INFO - 'other-e10s': {'tests': ['a11yr', 09:43:09 INFO - 'ts_paint', 09:43:09 INFO - 'tpaint', 09:43:09 INFO - 'sessionrestore', 09:43:09 INFO - 'sessionrestore_no_auto_restore', 09:43:09 INFO - 'tabpaint']}, 09:43:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:43:09 INFO - 'ts_paint', 09:43:09 INFO - 'tpaint', 09:43:09 INFO - 'sessionrestore', 09:43:09 INFO - 'sessionrestore_no_auto_restore', 09:43:09 INFO - 'tabpaint']}, 09:43:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:43:09 INFO - 'ts_paint', 09:43:09 INFO - 'tpaint', 09:43:09 INFO - 'sessionrestore', 09:43:09 INFO - 'sessionrestore_no_auto_restore', 09:43:09 INFO - 'tabpaint']}, 09:43:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:43:09 INFO - 'tests': ['a11yr', 09:43:09 INFO - 'ts_paint', 09:43:09 INFO - 'tpaint', 09:43:09 INFO - 'sessionrestore', 09:43:09 INFO - 'sessionrestore_no_auto_restore', 09:43:09 INFO - 'tabpaint']}, 09:43:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:43:09 INFO - 'tests': ['a11yr', 09:43:09 INFO - 'ts_paint', 09:43:09 INFO - 'tpaint', 09:43:09 INFO - 'sessionrestore', 09:43:09 INFO - 'sessionrestore_no_auto_restore', 09:43:09 INFO - 'tabpaint']}, 09:43:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:43:09 INFO - 'tests': ['tsvgx', 09:43:09 INFO - 'tsvgr_opacity', 09:43:09 INFO - 'tart', 09:43:09 INFO - 'tscrollx', 09:43:09 INFO - 'cart']}, 09:43:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:43:09 INFO - 'tsvgr_opacity', 09:43:09 INFO - 'tart', 09:43:09 INFO - 'tscrollx', 09:43:09 INFO - 'cart']}, 09:43:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:09 INFO - 'talos_options': ['--disable-e10s'], 09:43:09 INFO - 'tests': ['tp5o']}, 09:43:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:09 INFO - 'tests': ['tp5o']}, 09:43:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:09 INFO - 'talos_options': ['--disable-e10s', 09:43:09 INFO - '--xperf_path', 09:43:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:43:09 INFO - 'tests': ['tp5n']}, 09:43:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:09 INFO - 'talos_options': ['--xperf_path', 09:43:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:43:09 INFO - 'tests': ['tp5n']}}, 09:43:09 INFO - 'talos.zip': {'path': '', 09:43:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:43:09 INFO - Running post-action listener: _resource_record_post_action 09:43:09 INFO - ##### 09:43:09 INFO - ##### Finished populate-webroot step (success) 09:43:09 INFO - ##### 09:43:09 INFO - ##### 09:43:09 INFO - ##### Running create-virtualenv step. 09:43:09 INFO - ##### 09:43:09 INFO - Running pre-action listener: _resource_record_pre_action 09:43:09 INFO - Running main action method: create_virtualenv 09:43:09 INFO - Creating virtualenv /builds/slave/test/build/venv 09:43:09 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:43:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:43:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:43:09 INFO - Using real prefix '/tools/python27' 09:43:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:43:10 INFO - Installing distribute.............................................................................................................................................................................................done. 09:43:13 INFO - Installing pip.................done. 09:43:13 INFO - Return code: 0 09:43:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:43:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10473ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1047d0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83d0573cd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10469fd30>, '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 0x104404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104697b20>, '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.BTLrpfUGr5/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.Ad38fgbDsf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:43:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:43:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:43:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BTLrpfUGr5/Render', 09:43:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:13 INFO - 'HOME': '/Users/cltbld', 09:43:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:13 INFO - 'LOGNAME': 'cltbld', 09:43:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:13 INFO - 'MOZ_NO_REMOTE': '1', 09:43:13 INFO - 'NO_EM_RESTART': '1', 09:43:13 INFO - 'PAGER': '/bin/cat', 09:43:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:13 INFO - 'PWD': '/builds/slave/test', 09:43:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:13 INFO - 'SHELL': '/bin/bash', 09:43:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ad38fgbDsf/Listeners', 09:43:13 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:43:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:13 INFO - 'USER': 'cltbld', 09:43:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:13 INFO - 'XPC_FLAGS': '0x0', 09:43:13 INFO - 'XPC_SERVICE_NAME': '0', 09:43:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:43:13 INFO - Cleaning up... 09:43:13 INFO - Return code: 0 09:43:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:43:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10473ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1047d0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83d0573cd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10469fd30>, '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 0x104404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104697b20>, '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.BTLrpfUGr5/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.Ad38fgbDsf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:43:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:43:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:43:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BTLrpfUGr5/Render', 09:43:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:13 INFO - 'HOME': '/Users/cltbld', 09:43:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:13 INFO - 'LOGNAME': 'cltbld', 09:43:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:13 INFO - 'MOZ_NO_REMOTE': '1', 09:43:13 INFO - 'NO_EM_RESTART': '1', 09:43:13 INFO - 'PAGER': '/bin/cat', 09:43:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:13 INFO - 'PWD': '/builds/slave/test', 09:43:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:13 INFO - 'SHELL': '/bin/bash', 09:43:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ad38fgbDsf/Listeners', 09:43:13 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:43:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:13 INFO - 'USER': 'cltbld', 09:43:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:13 INFO - 'XPC_FLAGS': '0x0', 09:43:13 INFO - 'XPC_SERVICE_NAME': '0', 09:43:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:13 INFO - Downloading/unpacking psutil>=3.1.1 09:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:43:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:43:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:43:17 INFO - Installing collected packages: psutil 09:43:17 INFO - Running setup.py install for psutil 09:43:17 INFO - building 'psutil._psutil_osx' extension 09:43:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:43:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:43:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:43:17 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:43:17 INFO - building 'psutil._psutil_posix' extension 09:43:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:43:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:43:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:43:17 INFO - ^ 09:43:17 INFO - 1 warning generated. 09:43:17 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:43:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:43:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:43:17 INFO - Successfully installed psutil 09:43:17 INFO - Cleaning up... 09:43:17 INFO - Return code: 0 09:43:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:43: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')]} 09:43:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43: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')]} 09:43:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:17 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 0x10473ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1047d0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83d0573cd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10469fd30>, '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 0x104404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104697b20>, '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.BTLrpfUGr5/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.Ad38fgbDsf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:43:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:43:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:43:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BTLrpfUGr5/Render', 09:43:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:17 INFO - 'HOME': '/Users/cltbld', 09:43:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:17 INFO - 'LOGNAME': 'cltbld', 09:43:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:17 INFO - 'MOZ_NO_REMOTE': '1', 09:43:17 INFO - 'NO_EM_RESTART': '1', 09:43:17 INFO - 'PAGER': '/bin/cat', 09:43:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:17 INFO - 'PWD': '/builds/slave/test', 09:43:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:17 INFO - 'SHELL': '/bin/bash', 09:43:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ad38fgbDsf/Listeners', 09:43:17 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:43:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:17 INFO - 'USER': 'cltbld', 09:43:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:17 INFO - 'XPC_FLAGS': '0x0', 09:43:17 INFO - 'XPC_SERVICE_NAME': '0', 09:43:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:43:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:43:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:43:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:43:20 INFO - Installing collected packages: mozsystemmonitor 09:43:20 INFO - Running setup.py install for mozsystemmonitor 09:43:20 INFO - Successfully installed mozsystemmonitor 09:43:20 INFO - Cleaning up... 09:43:20 INFO - Return code: 0 09:43:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:43:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10473ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1047d0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83d0573cd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10469fd30>, '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 0x104404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104697b20>, '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.BTLrpfUGr5/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.Ad38fgbDsf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:43:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:43:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:43:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BTLrpfUGr5/Render', 09:43:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:20 INFO - 'HOME': '/Users/cltbld', 09:43:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:20 INFO - 'LOGNAME': 'cltbld', 09:43:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:20 INFO - 'MOZ_NO_REMOTE': '1', 09:43:20 INFO - 'NO_EM_RESTART': '1', 09:43:20 INFO - 'PAGER': '/bin/cat', 09:43:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:20 INFO - 'PWD': '/builds/slave/test', 09:43:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:20 INFO - 'SHELL': '/bin/bash', 09:43:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ad38fgbDsf/Listeners', 09:43:20 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:43:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:20 INFO - 'USER': 'cltbld', 09:43:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:20 INFO - 'XPC_FLAGS': '0x0', 09:43:20 INFO - 'XPC_SERVICE_NAME': '0', 09:43:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:21 INFO - Downloading/unpacking jsonschema==2.5.1 09:43:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:43:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:43:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:23 INFO - Downloading functools32-3.2.3-2.tar.gz 09:43:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:43:24 INFO - warning: no files found matching '*.txt' 09:43:24 INFO - no previously-included directories found matching 'build' 09:43:24 INFO - no previously-included directories found matching 'dist' 09:43:24 INFO - no previously-included directories found matching '.git*' 09:43:24 INFO - Installing collected packages: jsonschema, functools32 09:43:24 INFO - Running setup.py install for functools32 09:43:24 INFO - warning: no files found matching '*.txt' 09:43:24 INFO - no previously-included directories found matching 'build' 09:43:24 INFO - no previously-included directories found matching 'dist' 09:43:24 INFO - no previously-included directories found matching '.git*' 09:43:24 INFO - Successfully installed jsonschema functools32 09:43:24 INFO - Cleaning up... 09:43:24 INFO - Return code: 0 09:43:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:43:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10473ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1047d0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83d0573cd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10469fd30>, '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 0x104404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104697b20>, '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.BTLrpfUGr5/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.Ad38fgbDsf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:43:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:43:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:43:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BTLrpfUGr5/Render', 09:43:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:24 INFO - 'HOME': '/Users/cltbld', 09:43:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:24 INFO - 'LOGNAME': 'cltbld', 09:43:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:24 INFO - 'MOZ_NO_REMOTE': '1', 09:43:24 INFO - 'NO_EM_RESTART': '1', 09:43:24 INFO - 'PAGER': '/bin/cat', 09:43:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:24 INFO - 'PWD': '/builds/slave/test', 09:43:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:24 INFO - 'SHELL': '/bin/bash', 09:43:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ad38fgbDsf/Listeners', 09:43:24 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:43:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:24 INFO - 'USER': 'cltbld', 09:43:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:24 INFO - 'XPC_FLAGS': '0x0', 09:43:24 INFO - 'XPC_SERVICE_NAME': '0', 09:43:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:24 INFO - Downloading/unpacking blobuploader==1.2.4 09:43:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:27 INFO - Downloading blobuploader-1.2.4.tar.gz 09:43:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:43:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:43:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:43:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:28 INFO - Downloading docopt-0.6.1.tar.gz 09:43:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:43:28 INFO - Installing collected packages: blobuploader, requests, docopt 09:43:28 INFO - Running setup.py install for blobuploader 09:43:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:43:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:43:28 INFO - Running setup.py install for requests 09:43:29 INFO - Running setup.py install for docopt 09:43:29 INFO - Successfully installed blobuploader requests docopt 09:43:29 INFO - Cleaning up... 09:43:29 INFO - Return code: 0 09:43:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:43:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:29 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 0x10473ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1047d0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83d0573cd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10469fd30>, '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 0x104404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104697b20>, '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.BTLrpfUGr5/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.Ad38fgbDsf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:43:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:43:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:43:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BTLrpfUGr5/Render', 09:43:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:29 INFO - 'HOME': '/Users/cltbld', 09:43:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:29 INFO - 'LOGNAME': 'cltbld', 09:43:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:29 INFO - 'MOZ_NO_REMOTE': '1', 09:43:29 INFO - 'NO_EM_RESTART': '1', 09:43:29 INFO - 'PAGER': '/bin/cat', 09:43:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:29 INFO - 'PWD': '/builds/slave/test', 09:43:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:29 INFO - 'SHELL': '/bin/bash', 09:43:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ad38fgbDsf/Listeners', 09:43:29 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:43:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:29 INFO - 'USER': 'cltbld', 09:43:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:29 INFO - 'XPC_FLAGS': '0x0', 09:43:29 INFO - 'XPC_SERVICE_NAME': '0', 09:43:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:43:29 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-iHQbK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:43:29 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-TiWJb0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:43:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:43:29 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-EXeg2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:43:30 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-Xmg2Ve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:43:30 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-EgQXkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:43:30 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-CWVG5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:43:30 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-dsI4gt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:43:30 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-aaRA9z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:43:30 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-7gJW14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:43:30 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-ZEya8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:43:30 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-M2uUYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:43:30 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-tn8rb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:43:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:43:30 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-4LiH7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:43:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:43:31 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-6TBzVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:43:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:43:31 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-ky1j_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:43:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:43:31 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-z2uuqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:43:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:43:31 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-IofG7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:43:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:43:31 INFO - Running setup.py install for manifestparser 09:43:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:43:31 INFO - Running setup.py install for mozcrash 09:43:31 INFO - Running setup.py install for mozdebug 09:43:31 INFO - Running setup.py install for mozdevice 09:43:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:43:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:43:32 INFO - Running setup.py install for mozfile 09:43:32 INFO - Running setup.py install for mozhttpd 09:43:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:43:32 INFO - Running setup.py install for mozinfo 09:43:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:43:32 INFO - Running setup.py install for mozInstall 09:43:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:43:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:43:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:43:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:43:32 INFO - Running setup.py install for mozleak 09:43:32 INFO - Running setup.py install for mozlog 09:43:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:43:32 INFO - Running setup.py install for moznetwork 09:43:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:43:33 INFO - Running setup.py install for mozprocess 09:43:33 INFO - Running setup.py install for mozprofile 09:43:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:43:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:43:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:43:33 INFO - Running setup.py install for mozrunner 09:43:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:43:33 INFO - Running setup.py install for mozscreenshot 09:43:33 INFO - Running setup.py install for moztest 09:43:33 INFO - Running setup.py install for mozversion 09:43:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:43:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:43:34 INFO - Cleaning up... 09:43:34 INFO - Return code: 0 09:43:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:43:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:34 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 0x10473ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1047d0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83d0573cd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10469fd30>, '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 0x104404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104697b20>, '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.BTLrpfUGr5/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.Ad38fgbDsf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:43:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:43:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:43:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BTLrpfUGr5/Render', 09:43:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:34 INFO - 'HOME': '/Users/cltbld', 09:43:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:34 INFO - 'LOGNAME': 'cltbld', 09:43:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:34 INFO - 'MOZ_NO_REMOTE': '1', 09:43:34 INFO - 'NO_EM_RESTART': '1', 09:43:34 INFO - 'PAGER': '/bin/cat', 09:43:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:34 INFO - 'PWD': '/builds/slave/test', 09:43:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:34 INFO - 'SHELL': '/bin/bash', 09:43:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ad38fgbDsf/Listeners', 09:43:34 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:43:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:34 INFO - 'USER': 'cltbld', 09:43:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:34 INFO - 'XPC_FLAGS': '0x0', 09:43:34 INFO - 'XPC_SERVICE_NAME': '0', 09:43:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:43:34 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-aSXuW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:43:34 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-Ddu0Lu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:43:34 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-BINgca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:43:34 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-WX79YB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:43:34 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-HZrqKD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:43:34 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-BaW7NX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:43:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:43:34 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-7OiuAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:43:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:43:35 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-_Plc4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:43:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:43:35 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-QZb3Ai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:43:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:43:35 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-VHZsPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:43:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:43:35 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-ImhZrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:43:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:43:35 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-xXF_At-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:43:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:43:35 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-XLUv8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:43:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:43:35 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-NmOZVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:43:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:43:35 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-eNDCep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:43:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:43:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:43:35 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-xGDXri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:43:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:43:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:43:36 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-NJNIfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:43:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:43:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:43:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:43:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:43:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:43:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:43:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:43: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 09:43: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 09:43: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 09:43: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 09:43: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 09:43: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 09:43:38 INFO - Downloading blessings-1.6.tar.gz 09:43:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:43:38 INFO - Installing collected packages: blessings 09:43:38 INFO - Running setup.py install for blessings 09:43:38 INFO - Successfully installed blessings 09:43:38 INFO - Cleaning up... 09:43:38 INFO - Return code: 0 09:43:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:43:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:43:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:43:39 INFO - Reading from file tmpfile_stdout 09:43:39 INFO - Current package versions: 09:43:39 INFO - blessings == 1.6 09:43:39 INFO - blobuploader == 1.2.4 09:43:39 INFO - docopt == 0.6.1 09:43:39 INFO - functools32 == 3.2.3-2 09:43:39 INFO - jsonschema == 2.5.1 09:43:39 INFO - manifestparser == 1.1 09:43:39 INFO - mozInstall == 1.12 09:43:39 INFO - mozcrash == 0.17 09:43:39 INFO - mozdebug == 0.1 09:43:39 INFO - mozdevice == 0.48 09:43:39 INFO - mozfile == 1.2 09:43:39 INFO - mozhttpd == 0.7 09:43:39 INFO - mozinfo == 0.9 09:43:39 INFO - mozleak == 0.1 09:43:39 INFO - mozlog == 3.2 09:43:39 INFO - moznetwork == 0.27 09:43:39 INFO - mozprocess == 0.23 09:43:39 INFO - mozprofile == 0.28 09:43:39 INFO - mozrunner == 6.12 09:43:39 INFO - mozscreenshot == 0.1 09:43:39 INFO - mozsystemmonitor == 0.3 09:43:39 INFO - moztest == 0.7 09:43:39 INFO - mozversion == 1.4 09:43:39 INFO - psutil == 3.1.1 09:43:39 INFO - requests == 1.2.3 09:43:39 INFO - wsgiref == 0.1.2 09:43:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:43:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:39 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 0x10473ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1047d0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83d0573cd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10469fd30>, '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 0x104404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104697b20>, '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.BTLrpfUGr5/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.Ad38fgbDsf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:43:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:43:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:43:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BTLrpfUGr5/Render', 09:43:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:39 INFO - 'HOME': '/Users/cltbld', 09:43:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:39 INFO - 'LOGNAME': 'cltbld', 09:43:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:39 INFO - 'MOZ_NO_REMOTE': '1', 09:43:39 INFO - 'NO_EM_RESTART': '1', 09:43:39 INFO - 'PAGER': '/bin/cat', 09:43:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:39 INFO - 'PWD': '/builds/slave/test', 09:43:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:39 INFO - 'SHELL': '/bin/bash', 09:43:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ad38fgbDsf/Listeners', 09:43:39 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:43:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:39 INFO - 'USER': 'cltbld', 09:43:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:39 INFO - 'XPC_FLAGS': '0x0', 09:43:39 INFO - 'XPC_SERVICE_NAME': '0', 09:43:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:43:39 INFO - Cleaning up... 09:43:39 INFO - Return code: 0 09:43:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:43:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:39 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 0x10473ba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1047d0618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f83d0573cd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10469fd30>, '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 0x104404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104697b20>, '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.BTLrpfUGr5/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.Ad38fgbDsf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:43:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:43:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:43:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BTLrpfUGr5/Render', 09:43:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:39 INFO - 'HOME': '/Users/cltbld', 09:43:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:39 INFO - 'LOGNAME': 'cltbld', 09:43:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:39 INFO - 'MOZ_NO_REMOTE': '1', 09:43:39 INFO - 'NO_EM_RESTART': '1', 09:43:39 INFO - 'PAGER': '/bin/cat', 09:43:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:39 INFO - 'PWD': '/builds/slave/test', 09:43:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:39 INFO - 'SHELL': '/bin/bash', 09:43:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ad38fgbDsf/Listeners', 09:43:39 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:43:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:39 INFO - 'USER': 'cltbld', 09:43:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:39 INFO - 'XPC_FLAGS': '0x0', 09:43:39 INFO - 'XPC_SERVICE_NAME': '0', 09:43:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:43:39 INFO - Cleaning up... 09:43:39 INFO - Return code: 0 09:43:39 INFO - Running post-action listener: _resource_record_post_action 09:43:39 INFO - Running post-action listener: _start_resource_monitoring 09:43:39 INFO - Starting resource monitoring. 09:43:39 INFO - ##### 09:43:39 INFO - ##### Finished create-virtualenv step (success) 09:43:39 INFO - ##### 09:43:39 INFO - ##### 09:43:39 INFO - ##### Running install step. 09:43:39 INFO - ##### 09:43:39 INFO - Running pre-action listener: _resource_record_pre_action 09:43:39 INFO - Running main action method: install 09:43:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:43:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:43:40 INFO - Reading from file tmpfile_stdout 09:43:40 INFO - Detecting whether we're running mozinstall >=1.0... 09:43:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:43:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:43:40 INFO - Reading from file tmpfile_stdout 09:43:40 INFO - Output received: 09:43:40 INFO - Usage: mozinstall [options] installer 09:43:40 INFO - Options: 09:43:40 INFO - -h, --help show this help message and exit 09:43:40 INFO - -d DEST, --destination=DEST 09:43:40 INFO - Directory to install application into. [default: 09:43:40 INFO - "/builds/slave/test"] 09:43:40 INFO - --app=APP Application being installed. [default: firefox] 09:43:40 INFO - mkdir: /builds/slave/test/build/application 09:43:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:43:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:44:14 INFO - Reading from file tmpfile_stdout 09:44:14 INFO - Output received: 09:44:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:44:14 INFO - Running post-action listener: _resource_record_post_action 09:44:14 INFO - ##### 09:44:14 INFO - ##### Finished install step (success) 09:44:14 INFO - ##### 09:44:14 INFO - ##### 09:44:14 INFO - ##### Running run-tests step. 09:44:14 INFO - ##### 09:44:14 INFO - Running pre-action listener: _resource_record_pre_action 09:44:14 INFO - Running main action method: run_tests 09:44:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:44:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:44:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:44:14 INFO - 2016-07-14 09:44:14.553 screenresolution[1921:19778] starting screenresolution argv=screenresolution get 09:44:14 INFO - 2016-07-14 09:44:14.556 screenresolution[1921:19778] Display 0: 1600x1200x32@60 09:44:14 INFO - 2016-07-14 09:44:14.562 screenresolution[1922:19781] starting screenresolution argv=screenresolution list 09:44:14 INFO - Available Modes on Display 0 09:44:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:44:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:44:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:44:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:44:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:44:14 INFO - Intel Iris: 09:44:14 INFO - Chipset Model: Intel Iris 09:44:14 INFO - Type: GPU 09:44:14 INFO - Bus: Built-In 09:44:14 INFO - VRAM (Dynamic, Max): 1536 MB 09:44:14 INFO - Vendor: Intel (0x8086) 09:44:14 INFO - Device ID: 0x0a2e 09:44:14 INFO - Revision ID: 0x0009 09:44:14 INFO - Displays: 09:44:14 INFO - Display: 09:44:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:44:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:44:14 INFO - Display Serial Number: 1600x1200 60 09:44:14 INFO - Main Display: Yes 09:44:14 INFO - Mirror: Off 09:44:14 INFO - Online: Yes 09:44:14 INFO - Rotation: Supported 09:44:14 INFO - Return code: 0 09:44:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:44:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:44:14 INFO - Python 2.7.3 09:44:14 INFO - Return code: 0 09:44:14 INFO - grabbing minidump binary from tooltool 09:44:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:14 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 0x10469fd30>, '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 0x104404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104697b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:44:14 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:44:14 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:44:14 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:44:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:44:14 INFO - Return code: 0 09:44:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:44:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:44:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:44:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:44:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:44:14 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/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:44:14 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/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0012 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:44:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BTLrpfUGr5/Render', 09:44:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:14 INFO - 'HOME': '/Users/cltbld', 09:44:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:14 INFO - 'LOGNAME': 'cltbld', 09:44:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:44:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:44:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:14 INFO - 'MOZ_NO_REMOTE': '1', 09:44:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:44:14 INFO - 'NO_EM_RESTART': '1', 09:44:14 INFO - 'PAGER': '/bin/cat', 09:44:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:14 INFO - 'PWD': '/builds/slave/test', 09:44:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:44:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:14 INFO - 'SHELL': '/bin/bash', 09:44:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ad38fgbDsf/Listeners', 09:44:14 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 09:44:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:14 INFO - 'USER': 'cltbld', 09:44:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:14 INFO - 'XPC_FLAGS': '0x0', 09:44:14 INFO - 'XPC_SERVICE_NAME': '0', 09:44:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:14 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/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:44:15 INFO - mozversion application_buildid: 20160714064843 09:44:15 INFO - mozversion application_changeset: 6b9dae7e278dcd38d0b1c221ca9420931dfac7fd 09:44:15 INFO - mozversion application_display_name: Nightly 09:44:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:44:15 INFO - mozversion application_name: Firefox 09:44:15 INFO - mozversion application_remotingname: firefox 09:44:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:44:15 INFO - mozversion application_vendor: Mozilla 09:44:15 INFO - mozversion application_version: 50.0a1 09:44:15 INFO - mozversion platform_buildid: 20160714064843 09:44:15 INFO - mozversion platform_changeset: 6b9dae7e278dcd38d0b1c221ca9420931dfac7fd 09:44:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:44:15 INFO - mozversion platform_version: 50.0a1 09:44:15 INFO - using testdate: 1468514655 09:44:15 INFO - actual date: 1468514655 09:44:15 INFO - starting webserver on 'localhost:55989' 09:44:15 INFO - SUITE-START | Running 2 tests 09:44:15 INFO - TEST-START | dromaeo_css 09:44:15 INFO - operating with platform_type : mac_ 09:44:15 INFO - Initialising browser for dromaeo_css test... 09:44:15 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpEs5bdX/profile http://localhost:55989/getInfo.html) 09:44:17 INFO - PROCESS | 1932 | __metrics Screen width/height:1600/1200 09:44:17 INFO - PROCESS | 1932 | colorDepth:24 09:44:17 INFO - PROCESS | 1932 | Browser inner width/height: 1024/689 09:44:17 INFO - PROCESS | 1932 | __metrics 09:44:17 INFO - PROCESS | 1932 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:44:17 INFO - PROCESS | 1932 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:44:18 INFO - TEST-INFO | 1932: exit 0 09:44:18 INFO - Browser initialized. 09:44:18 INFO - Running cycle 1/1 for dromaeo_css test... 09:44:18 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpEs5bdX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:44:30 INFO - PROCESS | 1934 | RSS: Main: 243908608 09:44:30 INFO - PROCESS | 1934 | 09:45:21 INFO - PROCESS | 1934 | 1468514721546 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:47:31 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55989/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55989/tests/dromaeo/cssquery-ext.html) 09:47:33 INFO - PROCESS | 1934 | RSS: Main: 452767744 09:47:33 INFO - PROCESS | 1934 | 09:50:36 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55989/tests/dromaeo/cssquery-ext.html (next: http://localhost:55989/tests/dromaeo/cssquery-jquery.html) 09:50:37 INFO - PROCESS | 1934 | RSS: Main: 380796928 09:50:37 INFO - PROCESS | 1934 | 09:53:39 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55989/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55989/tests/dromaeo/cssquery-mootools.html) 09:53:40 INFO - PROCESS | 1934 | RSS: Main: 371613696 09:53:40 INFO - PROCESS | 1934 | 09:56:43 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55989/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55989/tests/dromaeo/cssquery-prototype.html) 09:56:43 INFO - PROCESS | 1934 | RSS: Main: 369795072 09:56:43 INFO - PROCESS | 1934 | 09:59:47 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55989/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55989/tests/dromaeo/cssquery-yui.html) 09:59:47 INFO - PROCESS | 1934 | RSS: Main: 333312000 09:59:47 INFO - PROCESS | 1934 | 10:02:50 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55989/tests/dromaeo/cssquery-yui.html (next: http://localhost:55989/tests/dromaeo/cssquery-dojo.html) 10:02:51 INFO - PROCESS | 1934 | RSS: Main: 391516160 10:02:51 INFO - PROCESS | 1934 | 10:02:51 INFO - PROCESS | 1934 | __start_tp_report 10:02:51 INFO - PROCESS | 1934 | _x_x_mozilla_page_load 10:02:51 INFO - PROCESS | 1934 | _x_x_mozilla_page_load_details 10:02:51 INFO - PROCESS | 1934 | |i|pagename|runs| 10:02:51 INFO - PROCESS | 1934 | |0;dojo.html;2963.695973771541;3129.8748050077993;3300.4719244920816;3331.6834900684457;3364.1925937774927;5473.726313684319;5499.835004949853;5550.195221692852;5572.136318870576;5626.127950167731;5528.143137813635;5580.051391263495;5673.120666296731;5708.286464191976;5720.656760594367;5020.899582008357;5047.520485553885;5104.979004199156;5126.512981266792;5270.657407268515;5158;5179;5190.221466779975;5212.869678258055;5224.085784987631;5045.823396181116;5088.745562721849;5098.949010509885;5142.97428512855;5143.971205758863;4437.534058923831;5053.924191137099;5056.873578160538;5132.871678208075;5140.768665410065;4212.410262563244;5023.14606516893;5049.6212784041345;5077.669951453144;5161.5870730341485;5426.457354264581;5551.833444996657;5650.576206784466;5657.830265092054;5661.377248502661;5940.702964851784;6069.393060693939;6088.883356155857;6123.6938153092615;6136.754529818846;6147.385261473862;6147.969260153715;6206.2862770781485;6219.626822390625;6256.7810126645345;5372.355317361941;5660.915086273709;5709.257796486471;5716.799912003102;5737.913931291034;6175.876482470415;6222.62664240147;6230.221222347207;6270.216222972129;6281.623102613858;6001.609895356788;6012.518998480111;6046.879062418727;6047.183630209954;6054.727537260834;5946.375630558816;5997.670128142994;6062.818115456544;6147.600405973598;6158.230221222348;6011.248593925759;6047.0929360596265;6068.908966365508;6079.392060793885;6085.117657939574;5067.6199285053035;6067.302260240041;6097.786577469856;6118.663473508911;6129.264488261437;4709.317149013443;4742.857714268577;4788.784504697297;4802.759862006886;4807;4334.371516130206;4347.260965635849;4356.978215108904;4377.3433984901885;4390.670699697535;5564.165375193672;5568.359638641527;5569.053260945649;5574.386817450078;5585.413531579207;6122.855605810045;6195.19462469876;6198.132261483396;6209.410106039919;6209.441150296405;5587.273654424899;5617.101263797855;5620.409856965041;5660.688662123623;5661.433856614306;5181.248718935733;5253.658512196695;5294.258803767476;5301.6818990860675;5321.813736519203;6160.860240855366;6207;6226.128342032029;6231.283402408691;6234.189555357865;6093.573449858467;6157.507399407947;6160.753569857335;6185.381461853958;6197.96901015492;6105.126180223362;6175.104609831459;6215.378462153749;6225.782097626561;6273.96863015682;5926.703664816828;5951.196588460675;5953.1367951647635;5964.582479226413;5976.342602313655;5059.342285502861;6048.909266361096;6076.756929722938;6083.391660833881;6095.451409373178;5141.974290128525;5920.111983202554;5955.255593050869;6039.305479869696;6054.0918862169265;5006.374203224597;5535.446455354432;5964.642121472725;5983.551233657406;5997.760089596367;5209.270702101785;5551.667599775944;5636.1827535008015;5827.650340979555;5831.650100993954;5829.038208695518;5901.026330655393;5916.615419997686;5922.703864806828;5923.644581325134;5732.369439361583;5741.3397459291045;5743.6840973745175;5750.281214848144;5755.8561035974435 10:02:51 INFO - PROCESS | 1934 | |1;ext.html;474.67721949074627;476.7640018190992;484.7479310758406;515.785948831235;569.7407679505825;36059;42649.36025959613;42725.78637106818;42860.78569607155;45078;43280.56719432805;43585;43713;43738.34392484107;43768.78115609418;8861.202491775739;8938.597763100644;9774.902250977506;9950.800983980316;9956.054174853414;12007.3395963222;12173.573924912629;12174.269543827355;12357.073219508527;12376.566820161295;12123.575674851381;12219.266843989344;12221.144519883612;12238.082143839203;12246.448909799035;8914.465132092095;8999.910000899972;9036.186743193079;9145.862812057825;12107.03143748498;11840.881591184065;12060.64817360615;12062;12287.754244915051;12292.016638668974;41491.585084149076;41921;41935.370969435484;41973.1605367894;41979.790101049606;3600.6579374959597;3676.7058635309113;3702.500162478058;4115.053537686319;4134.751914885086;26794.062207822633;31762;32753.67246327554;32950;33169;3284.293876816496;3319.717823984964;3326.334733053399;3342.832858357097;3375.3755555222115;447657;448753;452972;453123;453759;53905;55479.335619931466;63793.30170332141;64147.679261604324;64404;57223;65514.67242663851;67104.32895671079;67625.3237467619;67651;16531.66936661284;16704.331826726793;16723.247453864606;16748.162591870358;16756.245968931427;10647.94676026615;10655.893441065646;10761.623343183108;10762.946185269024;11063.889361106447;16505.504834854975;18175.81824181741;18627.627447450974;18632.97518636488;19066.2373505061;7101.762944672411;7167.283271672895;7188.101487314086;7225.89161162583;7254.8911766323545;916.867054277126;970.4807927758616;1037.8235699930879;1095.8794532601407;1105.7401510644993;29286.151305481148;30912.845435772677;31275.687243127733;31345.686543134732;31705.52441713376;1043.2071625564474;1046.006471979501;1052;1067.2955849139687;1144.164759725405;14009.929950350386;15538.456154034555;15785.526434206991;16154.919225403797;16498.83501164997;663706;664715;666779;667372;667717;60366;62177;62182.68908655518;62187.06719399212;62216.37783622197;1290.9677258068436;1332.3937608387967;1409.0770545292849;1429.6497358147112;1459.3214155417782;27966.720332796413;28753.85623071955;28914.27714307159;29582.556261656522;29586.852065739535;52743.20885186802;53300.73349633227;53448.198277026655;53493.930121396574;53518.46481535135;527329;541099;542219;543761;544723;36007;36826.26347473089;41755.7912210437;42721.572784273005;43813;48106.51893481021;49010.50989490059;49239.50760492349;50165.74917125391;50546.989060219326;38990.6100938987;40231.396529052676;40803.939704522876;41166;41620.37569436521;39353.21293574055;39494.2101157981;39902;40351;40655;38751;39459;39559.208815824095;39809.20381592294;39818.004549886486;33619.327613448084;35153;35411.33982668892;35511.46732798959;35921.82039089892;25802.61296080523;26001.739982600688;26117.608235876858;26540.84047652207;26920 10:02:51 INFO - PROCESS | 1934 | |2;jquery.html;354.18360678635753;356.74314493564657;373.41374042752875;386.6095243803759;395.3950455802623;15299;15784.947956765618;20737.963101844904;21226.46933826655;21687.891560542215;19637.607247855034;20230.797692023076;20663.896680516576;20951.895240523776;20960.89519552404;5881.5294776417995;6049.9697501512655;6111.3888611138755;6119.724612392453;6126.0504621783475;4208.852690155845;4659.998100408428;6044.425779550936;6717.26110127888;6799.558028728142;6689.665516724169;6706.5976041437425;6715.227748808877;6719.8656026879435;6727.899081513757;6440.5491615586925;6701.195856497203;6703.195616525999;6704.765333213314;6706.429953453938;3738.327101121797;5443.646162999432;5755.827325180251;5762.827115186551;5797.8550536236935;23757.812109394636;24095.759042409525;25744.87127564369;25871;26190;2229.6321107017325;2309.6766452696634;2372.454335502844;2513.1832154549843;2532.3669082729316;14440.55594440558;14514.201718905457;15513.92243038789;16354.672906541802;16452.506424807274;1715.7389318850635;1868.953276168093;1946.6593346164507;1974.3089918528544;2006.8695534790193;119244.40377798142;121114.78885211212;122020;122500;122881.99999999911;9690.95154524223;10457.84313235287;10687.679369618923;11059.557617695202;11154.442277886073;12604.55884044054;12941.741165176643;13322.73354532904;13330.933345333406;13632.591022269347;8710.869336959951;8735.213830755263;8789.472631642122;8856.690015849543;8877.822443551093;8842.425242359226;8857.3799834011;8864.8670269946;8987.46075235488;9002.459852408876;5626.268585083994;6545.280019197884;6636.369544893228;6641.634710090992;6694.49791265657;1838.4576549917806;1873.7376767252595;1875.2873907914905;1878.9624207515774;1880.9623807523774;776.7397921696295;939.8449255872841;941.1136016150298;971.1647982734843;1000.9149222316184;21242.468938276666;21876.671849922262;21882.23412180566;21895.12419503234;21912.013959371867;973.8052389522123;1004.4073996342194;1007.2948935745007;1024.6772266735954;1091.8908109189017;11018.669439916815;11562.364069976233;11585.246958947642;11700.824487632692;11734.178607497564;554882;556195;562921;586340;586908;73415;73933;114721.27918081236;118787.8121218794;121560.39219804021;862.5040601654074;1031.2214278219897;1053.8577292065627;1055.3878750324948;1070.5129166229347;18630.44108676742;19409.029548522798;19435.3197638082;19473.221071156993;19580.412587622395;48827.51172488372;56816.71591642015;59463.51341216504;60849.69575152096;61364.38635613587;62545.37454625578;86827;88644.55677721571;89876;91301;7971.601419929097;8128.796780080545;8166.714165004197;8448.408611397143;8461.111583283666;9334.673286435214;12557.74884502323;13429.395677194354;13729.45082196701;13885.791713124514;10188.796224075626;11439.199256051996;11878.227915185485;11895.405229738306;11949.581764638544;11479;11517.827232591686;11566.65300041;11896.22674526153;11904;11718.824217636571;11744.471498782746;11927.522899084286;12027.699307517381;12029.518819247132;7120.216776154515;8146.796330091795;8270.338372930031;8349.248567628943;8473.703420380258;9649;11125.054370378284;11616.244944078608;11644.475998579916;11756 10:02:51 INFO - PROCESS | 1934 | |3;mootools.html;1409.8167238259039;1428.3501007041793;1532.7394342961695;1717.5877789330557;1965.3121407507374;7786.377089832814;8554.529500877457;8785.736427907157;8795.648174073045;8799.648014079445;8403.159684031594;8725.040245572982;8799.164079412461;8892.91107088929;8903;2101.7162683037823;2179.1501314487364;2344.7069116360453;2392.4616961183724;2412.2401443545227;2423.3578101803037;2437.19572541061;2439.817013723969;2440.389902524369;2442.511497700458;2381.8570885746826;2394.976050239493;2422.878856057199;2424.624183251599;2424.8908799103992;1667.674803413341;2309.3533810533077;2364.042562762084;2370.3244575295957;2370.8577485350934;2392.413858604653;2418.238254949685;2418.5888398972215;2419.6128619420806;2421.406755344934;8963.731088067303;9040.276777857756;9047.68333108338;9048.547572621343;9053.185213330766;5164.690118592859;5181.818636347747;5218.843434696965;5288.206768984644;5299.364076310829;3421.469672200813;3553.928921421557;3683.4658974448553;3690.5940346561856;3704.0554658562164;1084.0839490630406;1101.1631160318136;1109.1404161774608;1129.7571022230177;1130.8699499557574;11824.9948754356;12074.577389791402;12078.15452918296;12100.515979360815;12101.636950891487;3933.3116704577155;4627.583517483443;4876.024795041006;4876.244182151772;4879.170541008037;10377.273590848568;10380.896191037993;10382.221333399873;10385.740356491147;10394.740131496774;5421.4578542146255;5429.077056900336;5432.185172224119;5432.728363581884;5441.83674489766;3776.3202623527477;4551.40831691878;4777.116233496814;4881.731504767272;4892.730899800474;4051.716379853441;4092.7339722917914;4123.670106391482;4182.728122672017;4186.665066794649;1085.880553139154;1209.679434949739;1286.3761075878192;1336.3117994232978;1346.9057165998286;805.3114587028124;806.3911746631256;918.9632414703337;977.4379731654327;1016.8560369584218;6135.202423684982;6140.662263575547;6150.7232174552255;6340.270868850049;6349.6507692077375;967.6274634265858;983.3755565215996;996.9202463802878;1016.242899040205;1019.1541020952592;3934.7048971327263;4069.369247766601;4096;4124.319487284564;4169.645580125723;10741.570337186582;10956.34261944286;11157.553697852158;11164.776704466027;11172.217944743952;11097.944510277397;11105.722356941142;11111.333320000826;11130.053945414731;11142.721431964266;7199.280071992759;8242.505449673039;8247.010358757108;8339.416240863202;8507.65969361231;6094.512439004958;6128.356522565245;6153.107799368977;6246;6315.526335524946;9962.302638815214;10125;10429.58281668755;10441.895581044093;10460;10256;10573.04842564173;10598.576056957636;10618.946905265424;10654.414007229676;7768.223177682186;7769.339606133542;7818.452708310591;7857.332126769289;7886.408519361185;8434.957825210835;8533.232009118961;8538.530380829114;8541.444806087584;8559.61481733336;7484.850302994018;7646.502977306457;7667.233276672511;7798.688052477837;7804.453688241769;6646.302138275605;7539.736109236151;7550.282723141293;7562.470627056054;7599.620018998918;5537.501624853782;6260.280067792262;7260;7260.782176534713;7265.782026539001;5107.259447380184;5160.716160611202;5206.01085793698;5209.53114219722;5331.280277162688;4869.123557759637;5118.846434606968;5170.121079416584;5259.368875734936;5353.946460535501 10:02:51 INFO - PROCESS | 1934 | |4;prototype.html;303.45984148216166;303.8101186758276;317.45081009852953;319.1940350614699;321.4663658327177;4830.647418722754;5024.24636304554;5153.330067091278;5213.9478605213935;5218.295530103435;4337.4795024597015;4895.437024742156;5110.667806592569;5153.252778347138;5184.611154163434;3915.647591716745;3970.54338751043;3973.1457736586626;3989.760614363133;4006.8597599084037;3968.6825053995753;3985;3985.4293980382636;4009.4787677602017;4009.777018009506;3361.411752943225;3404.285100128976;3874.9031274218087;3969.920601587981;3976.741511801724;3109.7667674924246;3582.4089025310923;3684.6499582539614;3890.2414029264305;3930.64624183822;3452.5486904297977;3460.5501284832812;3462.7056700180515;3475.8088305143206;3486.6164721880573;4866.294387313855;5053.9241911371355;5097.317885097908;5122.231665250205;5135.64050516464;4513.7275475280485;4530.682852200347;4539.682222244444;4557.293619488984;4569.771511424448;4255.91488170238;4348.977990172307;5384.788422604922;5391.676499410009;5415.377231618336;3397.83010849459;3804.885853424402;3835.42468629705;3846.769193848378;3852.4028775539837;4756.548127927841;5285.709285989269;5534.0315444797;5671.7731290748325;5674.631148975344;3327.7005069543375;3399.7450191235757;3600.3519366513756;3765.378712512405;3795.4306853972125;3265.722413594871;3872.8838134856;3876.7286289959434;3881.3595756700392;3892.2484881803302;3790.938537209586;4230.534641189467;4460.018795864969;4481.35020422037;4498.065831692338;4496.752678602643;4901.828436004723;5104.3364362633365;5189.247559103985;5432.674039557639;1196.5871774237874;1212.3574505511917;1230.5939040116739;1269.073576289314;1310.8099325597925;822.8642274024838;858.6694122762781;883.3330835219497;885.3271513649672;887.3078998381272;4360.324149756792;5023.271625614266;5044.571211446994;5059.266406371129;5072.695638261716;4691.319758634979;5564.944350556523;5566.666000040011;5574.60977731563;5599.356074051536;4809.95190048102;5150.974245128826;5180.896382072413;5194.545627157898;5219.947800522022;2730.836149830977;2902.6371703537056;2936.8972085977316;2969.3615872587065;2984.5224764038085;4890.021995600894;5432.348118225838;5433.728313584305;5490.396056433869;5800.767969281266;4860.618297034498;5747.885042299131;5770.365259821417;5789.652620842763;5803.82588522345;5213.530782229542;5775.826725198251;5820.505257053198;5823.56323275756;5827.766889324549;5153.665011774223;5192.351900530832;5446.556662484524;5650.491455769001;5658.717064146858;5533.391326954113;5546.528545073553;5566.916496252478;5570.493278024824;5581.5813813963305;5358.035553600389;5408.78364865401;5511.559075274049;5795.971020144872;5840.357560668408;3328.683775041367;3456.3087382523095;3571.696330839277;3678.429843374281;3798.1833905710405;4462.687611867138;4736.976315118402;5622.128570071645;5673.071155807045;5681.346645135863;4640.8137317228175;4756.9524304756505;4896.36347274852;5435.239066530768;5544.500994910478;4802.5112215212;5438.918416223681;5450.563954883679;5464.726763661722;5467.480589344006;4170.603792639695;4340.8697739067875;5237.685738855528;5289.646107482134;5381.058314794817;5446.455354464521;5481.698506582176;5487.451254874481;5492.105223697818;5515.61390702647;4286.80709368073;4654.672663668166;4952.702837829742;5342.679439233659;5390.434004429635 10:02:51 INFO - PROCESS | 1934 | |5;yui.html;711.1856923822223;746.5371469688793;752.6763491698567;796.219704689404;874.9125087491252;15035.398584056638;16301.918490407532;16317.918410407932;16324.755128673065;16333.020018798881;14512.927435362808;14625.92687036566;14741.115533068023;14777;14948.327325270362;716.4089626058496;886.8359353519603;913.4017218721748;913.4930113786845;915.9129882661136;726.687524364523;880.9427387219843;921.8202450522151;923.3305853256403;923.7044145873323;967.351874244258;967.8499832525935;968.1915600473613;970.2916870684404;970.8106919150769;736.9594672293022;923.4920793563513;954.7708549948032;956.9999350678557;958.650092716157;716.1728203924423;716.9211386747454;717.1716667249335;717.2540557819862;723.2658851265969;14995.700085998327;15044.548663540112;15064.171470569114;15067.924660376737;15099.320530576151;13467.326633668277;13945.930270348685;14043.929780351033;14085.225312607803;14224.644383890382;12712.491500339975;12720.236785792882;12838.229706217657;12996.220226786423;13180.604581862559;4516.16450956574;4537.342085397599;4563.110193512266;4686.906261874778;4729.101470720552;114947;117090;117426;118088.40955795166;119881.40059299735;11107.38909359993;11114;11129.054030407506;11204.271722338022;11319.490622921989;21482.355529334145;21587.244446444613;21634.78365216359;21674.02466888994;22061.558768824856;7993.440459167802;8004.599770011476;8083.838323233503;8213.79465513355;8265;4468.017036252019;4563.38394316763;4598.632109431237;4784.6889952153;4838.588719958843;3199.632042315117;3356.009977056774;3356.9328613427597;3384.6446123157207;3384.9845046485957;686.2588404523203;693.6843736100062;728.5555810955314;747.872861613527;748.5246868238708;648.2836465705328;651.6188030002406;678.3860606151441;697.6197972105215;710.8542748736496;9536.52317384128;9560.665376711782;9703.563339649732;9771.31600787938;9805.970373110864;677.8881484554993;688.9827754306082;695.5027155583782;697.9916559628019;708.6031822179596;4111.054457474797;4570.588647021784;4744.501827308152;4747.192977193886;4748.596369308579;101879.98120018853;131056.3447182758;154624;154760;171856;145234.2738286302;146825;146988.26505867616;147496;156905;116019.41990289994;120635.39682301707;120691.39654301848;121551.39224303822;121630.39184804019;9122.407043542149;9143.268538517035;9187.540622968958;9228.292944648116;9473.289503287418;66249.66875165593;66552.6672366635;67318;67803;67945.66027169832;89405;95190.52404737932;99383.50308248412;102026.48986755019;103901;13949.16305021702;14019.92990035084;14090.436382544583;14331;14421.927890360901;14779.92610036943;14989.850101498845;15134;15680.215989200722;16348.509544713677;13879.167249965034;14214.360353783897;14239.35922883452;14479.710405792035;14640.487582934973;14266.358013889192;14284.928575357057;14306.785398218826;14317.355718992461;14347.92826035905;12764.68088297763;13144;13257.469701211843;13297.601071967856;13324.400401982128;11242.606508772154;11631.825522616999;11647.76704465923;11783.293002419883;11892.76214475723;10031.799364012533;10625.724913010488;11406.714832129263;11461.426928653367;11542.422878856192 10:02:51 INFO - PROCESS | 1934 | __end_tp_report 10:02:51 INFO - PROCESS | 1934 | __start_cc_report 10:02:51 INFO - PROCESS | 1934 | _x_x_mozilla_cycle_collect,2191 10:02:51 INFO - PROCESS | 1934 | __end_cc_report 10:02:51 INFO - PROCESS | 1934 | __startTimestamp1468515771131__endTimestamp 10:02:51 INFO - PROCESS | 1934 | 10:02:51 INFO - PROCESS | 1934 | ------- Summary: start ------- 10:02:51 INFO - PROCESS | 1934 | Number of tests: 6 10:02:51 INFO - PROCESS | 1934 | 10:02:51 INFO - PROCESS | 1934 | [#0] dojo.html Cycles:165 Average:5588.76 Median:5732.37 stddev:651.73 (11.4%) stddev-sans-first:620.34 10:02:51 INFO - PROCESS | 1934 | Values: 2963.7 3129.9 3300.5 3331.7 3364.2 5473.7 5499.8 5550.2 5572.1 5626.1 5528.1 5580.1 5673.1 5708.3 5720.7 5020.9 5047.5 5105.0 5126.5 5270.7 5158.0 5179.0 5190.2 5212.9 5224.1 5045.8 5088.7 5098.9 5143.0 5144.0 4437.5 5053.9 5056.9 5132.9 5140.8 4212.4 5023.1 5049.6 5077.7 5161.6 5426.5 5551.8 5650.6 5657.8 5661.4 5940.7 6069.4 6088.9 6123.7 6136.8 6147.4 6148.0 6206.3 6219.6 6256.8 5372.4 5660.9 5709.3 5716.8 5737.9 6175.9 6222.6 6230.2 6270.2 6281.6 6001.6 6012.5 6046.9 6047.2 6054.7 5946.4 5997.7 6062.8 6147.6 6158.2 6011.2 6047.1 6068.9 6079.4 6085.1 5067.6 6067.3 6097.8 6118.7 6129.3 4709.3 4742.9 4788.8 4802.8 4807.0 4334.4 4347.3 4357.0 4377.3 4390.7 5564.2 5568.4 5569.1 5574.4 5585.4 6122.9 6195.2 6198.1 6209.4 6209.4 5587.3 5617.1 5620.4 5660.7 5661.4 5181.2 5253.7 5294.3 5301.7 5321.8 6160.9 6207.0 6226.1 6231.3 6234.2 6093.6 6157.5 6160.8 6185.4 6198.0 6105.1 6175.1 6215.4 6225.8 6274.0 5926.7 5951.2 5953.1 5964.6 5976.3 5059.3 6048.9 6076.8 6083.4 6095.5 5142.0 5920.1 5955.3 6039.3 6054.1 5006.4 5535.4 5964.6 5983.6 5997.8 5209.3 5551.7 5636.2 5827.7 5831.7 5829.0 5901.0 5916.6 5922.7 5923.6 5732.4 5741.3 5743.7 5750.3 5755.9 10:02:51 INFO - PROCESS | 1934 | 10:02:51 INFO - PROCESS | 1934 | [#1] ext.html Cycles:180 Average:70073.87 Median:39684.21 stddev:149226.29 (376.0%) stddev-sans-first:149553.41 10:02:51 INFO - PROCESS | 1934 | Values: 474.7 476.8 484.7 515.8 569.7 36059.0 42649.4 42725.8 42860.8 45078.0 43280.6 43585.0 43713.0 43738.3 43768.8 8861.2 8938.6 9774.9 9950.8 9956.1 12007.3 12173.6 12174.3 12357.1 12376.6 12123.6 12219.3 12221.1 12238.1 12246.4 8914.5 8999.9 9036.2 9145.9 12107.0 11840.9 12060.6 12062.0 12287.8 12292.0 41491.6 41921.0 41935.4 41973.2 41979.8 3600.7 3676.7 3702.5 4115.1 4134.8 26794.1 31762.0 32753.7 32950.0 33169.0 3284.3 3319.7 3326.3 3342.8 3375.4 447657.0 448753.0 452972.0 453123.0 453759.0 53905.0 55479.3 63793.3 64147.7 64404.0 57223.0 65514.7 67104.3 67625.3 67651.0 16531.7 16704.3 16723.2 16748.2 16756.2 10647.9 10655.9 10761.6 10762.9 11063.9 16505.5 18175.8 18627.6 18633.0 19066.2 7101.8 7167.3 7188.1 7225.9 7254.9 916.9 970.5 1037.8 1095.9 1105.7 29286.2 30912.8 31275.7 31345.7 31705.5 1043.2 1046.0 1052.0 1067.3 1144.2 14009.9 15538.5 15785.5 16154.9 16498.8 663706.0 664715.0 666779.0 667372.0 667717.0 60366.0 62177.0 62182.7 62187.1 62216.4 1291.0 1332.4 1409.1 1429.6 1459.3 27966.7 28753.9 28914.3 29582.6 29586.9 52743.2 53300.7 53448.2 53493.9 53518.5 527329.0 541099.0 542219.0 543761.0 544723.0 36007.0 36826.3 41755.8 42721.6 43813.0 48106.5 49010.5 49239.5 50165.7 50547.0 38990.6 40231.4 40803.9 41166.0 41620.4 39353.2 39494.2 39902.0 40351.0 40655.0 38751.0 39459.0 39559.2 39809.2 39818.0 33619.3 35153.0 35411.3 35511.5 35921.8 25802.6 26001.7 26117.6 26540.8 26920.0 10:02:51 INFO - PROCESS | 1934 | 10:02:51 INFO - PROCESS | 1934 | [#2] jquery.html Cycles:180 Average:34119.42 Median:2341.07 stddev:94919.74 (4054.6%) stddev-sans-first:95152.15 10:02:51 INFO - PROCESS | 1934 | Values: 354.2 356.7 373.4 386.6 395.4 15299.0 15784.9 20738.0 21226.5 21687.9 19637.6 20230.8 20663.9 20951.9 20960.9 5881.5 6050.0 6111.4 6119.7 6126.1 4208.9 4660.0 6044.4 6717.3 6799.6 6689.7 6706.6 6715.2 6719.9 6727.9 6440.5 6701.2 6703.2 6704.8 6706.4 3738.3 5443.6 5755.8 5762.8 5797.9 23757.8 24095.8 25744.9 25871.0 26190.0 2229.6 2309.7 2372.5 2513.2 2532.4 14440.6 14514.2 15513.9 16354.7 16452.5 1715.7 1869.0 1946.7 1974.3 2006.9 119244.4 121114.8 122020.0 122500.0 122882.0 9691.0 10457.8 10687.7 11059.6 11154.4 12604.6 12941.7 13322.7 13330.9 13632.6 8710.9 8735.2 8789.5 8856.7 8877.8 8842.4 8857.4 8864.9 8987.5 9002.5 5626.3 6545.3 6636.4 6641.6 6694.5 1838.5 1873.7 1875.3 1879.0 1881.0 776.7 939.8 941.1 971.2 1000.9 21242.5 21876.7 21882.2 21895.1 21912.0 973.8 1004.4 1007.3 1024.7 1091.9 11018.7 11562.4 11585.2 11700.8 11734.2 554882.0 556195.0 562921.0 586340.0 586908.0 73415.0 73933.0 114721.3 118787.8 121560.4 862.5 1031.2 1053.9 1055.4 1070.5 18630.4 19409.0 19435.3 19473.2 19580.4 48827.5 56816.7 59463.5 60849.7 61364.4 62545.4 86827.0 88644.6 89876.0 91301.0 7971.6 8128.8 8166.7 8448.4 8461.1 9334.7 12557.7 13429.4 13729.5 13885.8 10188.8 11439.2 11878.2 11895.4 11949.6 11479.0 11517.8 11566.7 11896.2 11904.0 11718.8 11744.5 11927.5 12027.7 12029.5 7120.2 8146.8 8270.3 8349.2 8473.7 9649.0 11125.1 11616.2 11644.5 11756.0 10:02:51 INFO - PROCESS | 1934 | 10:02:51 INFO - PROCESS | 1934 | [#3] mootools.html Cycles:180 Average:5834.62 Median:4702.35 stddev:3308.31 (70.4%) stddev-sans-first:3300.88 10:02:51 INFO - PROCESS | 1934 | Values: 1409.8 1428.4 1532.7 1717.6 1965.3 7786.4 8554.5 8785.7 8795.6 8799.6 8403.2 8725.0 8799.2 8892.9 8903.0 2101.7 2179.2 2344.7 2392.5 2412.2 2423.4 2437.2 2439.8 2440.4 2442.5 2381.9 2395.0 2422.9 2424.6 2424.9 1667.7 2309.4 2364.0 2370.3 2370.9 2392.4 2418.2 2418.6 2419.6 2421.4 8963.7 9040.3 9047.7 9048.5 9053.2 5164.7 5181.8 5218.8 5288.2 5299.4 3421.5 3553.9 3683.5 3690.6 3704.1 1084.1 1101.2 1109.1 1129.8 1130.9 11825.0 12074.6 12078.2 12100.5 12101.6 3933.3 4627.6 4876.0 4876.2 4879.2 10377.3 10380.9 10382.2 10385.7 10394.7 5421.5 5429.1 5432.2 5432.7 5441.8 3776.3 4551.4 4777.1 4881.7 4892.7 4051.7 4092.7 4123.7 4182.7 4186.7 1085.9 1209.7 1286.4 1336.3 1346.9 805.3 806.4 919.0 977.4 1016.9 6135.2 6140.7 6150.7 6340.3 6349.7 967.6 983.4 996.9 1016.2 1019.2 3934.7 4069.4 4096.0 4124.3 4169.6 10741.6 10956.3 11157.6 11164.8 11172.2 11097.9 11105.7 11111.3 11130.1 11142.7 7199.3 8242.5 8247.0 8339.4 8507.7 6094.5 6128.4 6153.1 6246.0 6315.5 9962.3 10125.0 10429.6 10441.9 10460.0 10256.0 10573.0 10598.6 10618.9 10654.4 7768.2 7769.3 7818.5 7857.3 7886.4 8435.0 8533.2 8538.5 8541.4 8559.6 7484.9 7646.5 7667.2 7798.7 7804.5 6646.3 7539.7 7550.3 7562.5 7599.6 5537.5 6260.3 7260.0 7260.8 7265.8 5107.3 5160.7 5206.0 5209.5 5331.3 4869.1 5118.8 5170.1 5259.4 5353.9 10:02:51 INFO - PROCESS | 1934 | 10:02:51 INFO - PROCESS | 1934 | [#4] prototype.html Cycles:180 Average:4378.89 Median:4892.73 stddev:1343.49 (27.5%) stddev-sans-first:1311.97 10:02:51 INFO - PROCESS | 1934 | Values: 303.5 303.8 317.5 319.2 321.5 4830.6 5024.2 5153.3 5213.9 5218.3 4337.5 4895.4 5110.7 5153.3 5184.6 3915.6 3970.5 3973.1 3989.8 4006.9 3968.7 3985.0 3985.4 4009.5 4009.8 3361.4 3404.3 3874.9 3969.9 3976.7 3109.8 3582.4 3684.6 3890.2 3930.6 3452.5 3460.6 3462.7 3475.8 3486.6 4866.3 5053.9 5097.3 5122.2 5135.6 4513.7 4530.7 4539.7 4557.3 4569.8 4255.9 4349.0 5384.8 5391.7 5415.4 3397.8 3804.9 3835.4 3846.8 3852.4 4756.5 5285.7 5534.0 5671.8 5674.6 3327.7 3399.7 3600.4 3765.4 3795.4 3265.7 3872.9 3876.7 3881.4 3892.2 3790.9 4230.5 4460.0 4481.4 4498.1 4496.8 4901.8 5104.3 5189.2 5432.7 1196.6 1212.4 1230.6 1269.1 1310.8 822.9 858.7 883.3 885.3 887.3 4360.3 5023.3 5044.6 5059.3 5072.7 4691.3 5564.9 5566.7 5574.6 5599.4 4810.0 5151.0 5180.9 5194.5 5219.9 2730.8 2902.6 2936.9 2969.4 2984.5 4890.0 5432.3 5433.7 5490.4 5800.8 4860.6 5747.9 5770.4 5789.7 5803.8 5213.5 5775.8 5820.5 5823.6 5827.8 5153.7 5192.4 5446.6 5650.5 5658.7 5533.4 5546.5 5566.9 5570.5 5581.6 5358.0 5408.8 5511.6 5796.0 5840.4 3328.7 3456.3 3571.7 3678.4 3798.2 4462.7 4737.0 5622.1 5673.1 5681.3 4640.8 4757.0 4896.4 5435.2 5544.5 4802.5 5438.9 5450.6 5464.7 5467.5 4170.6 4340.9 5237.7 5289.6 5381.1 5446.5 5481.7 5487.5 5492.1 5515.6 4286.8 4654.7 4952.7 5342.7 5390.4 10:02:51 INFO - PROCESS | 1934 | 10:02:51 INFO - PROCESS | 1934 | [#5] yui.html Cycles:180 Average:26254.84 Median:21867.79 stddev:42260.27 (193.3%) stddev-sans-first:42335.30 10:02:51 INFO - PROCESS | 1934 | Values: 711.2 746.5 752.7 796.2 874.9 15035.4 16301.9 16317.9 16324.8 16333.0 14512.9 14625.9 14741.1 14777.0 14948.3 716.4 886.8 913.4 913.5 915.9 726.7 880.9 921.8 923.3 923.7 967.4 967.8 968.2 970.3 970.8 737.0 923.5 954.8 957.0 958.7 716.2 716.9 717.2 717.3 723.3 14995.7 15044.5 15064.2 15067.9 15099.3 13467.3 13945.9 14043.9 14085.2 14224.6 12712.5 12720.2 12838.2 12996.2 13180.6 4516.2 4537.3 4563.1 4686.9 4729.1 114947.0 117090.0 117426.0 118088.4 119881.4 11107.4 11114.0 11129.1 11204.3 11319.5 21482.4 21587.2 21634.8 21674.0 22061.6 7993.4 8004.6 8083.8 8213.8 8265.0 4468.0 4563.4 4598.6 4784.7 4838.6 3199.6 3356.0 3356.9 3384.6 3385.0 686.3 693.7 728.6 747.9 748.5 648.3 651.6 678.4 697.6 710.9 9536.5 9560.7 9703.6 9771.3 9806.0 677.9 689.0 695.5 698.0 708.6 4111.1 4570.6 4744.5 4747.2 4748.6 101880.0 131056.3 154624.0 154760.0 171856.0 145234.3 146825.0 146988.3 147496.0 156905.0 116019.4 120635.4 120691.4 121551.4 121630.4 9122.4 9143.3 9187.5 9228.3 9473.3 66249.7 66552.7 67318.0 67803.0 67945.7 89405.0 95190.5 99383.5 102026.5 103901.0 13949.2 14019.9 14090.4 14331.0 14421.9 14779.9 14989.9 15134.0 15680.2 16348.5 13879.2 14214.4 14239.4 14479.7 14640.5 14266.4 14284.9 14306.8 14317.4 14347.9 12764.7 13144.0 13257.5 13297.6 13324.4 11242.6 11631.8 11647.8 11783.3 11892.8 10031.8 10625.7 11406.7 11461.4 11542.4 10:02:51 INFO - PROCESS | 1934 | -------- Summary: end -------- 10:02:51 INFO - PROCESS | 1934 | 10:02:51 INFO - TEST-INFO | 1934: exit 0 10:02:51 INFO - TEST-OK | dromaeo_css | took 1116629ms 10:02:51 INFO - TEST-START | kraken 10:02:51 INFO - operating with platform_type : mac_ 10:02:51 INFO - Initialising browser for kraken test... 10:02:51 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpLQFs2L/profile http://localhost:55989/getInfo.html) 10:02:54 INFO - PROCESS | 1937 | __metrics Screen width/height:1600/1200 10:02:54 INFO - PROCESS | 1937 | colorDepth:24 10:02:54 INFO - PROCESS | 1937 | Browser inner width/height: 1024/689 10:02:54 INFO - PROCESS | 1937 | __metrics 10:02:54 INFO - PROCESS | 1937 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:02:54 INFO - PROCESS | 1937 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:02:55 INFO - TEST-INFO | 1937: exit 0 10:02:55 INFO - Browser initialized. 10:02:55 INFO - Running cycle 1/1 for kraken test... 10:02:55 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpLQFs2L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:03:06 INFO - PROCESS | 1938 | RSS: Main: 243970048 10:03:06 INFO - PROCESS | 1938 | 10:03:09 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55989/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55989/tests/kraken/driver.html?testName=audio-beat-detection) 10:03:10 INFO - PROCESS | 1938 | RSS: Main: 294592512 10:03:10 INFO - PROCESS | 1938 | 10:03:12 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55989/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55989/tests/kraken/driver.html?testName=audio-dft) 10:03:13 INFO - PROCESS | 1938 | RSS: Main: 304873472 10:03:13 INFO - PROCESS | 1938 | 10:03:17 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55989/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55989/tests/kraken/driver.html?testName=audio-fft) 10:03:17 INFO - PROCESS | 1938 | RSS: Main: 393412608 10:03:17 INFO - PROCESS | 1938 | 10:03:19 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55989/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55989/tests/kraken/driver.html?testName=audio-oscillator) 10:03:20 INFO - PROCESS | 1938 | RSS: Main: 293142528 10:03:20 INFO - PROCESS | 1938 | 10:03:22 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55989/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55989/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:03:23 INFO - PROCESS | 1938 | RSS: Main: 297205760 10:03:23 INFO - PROCESS | 1938 | 10:03:25 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55989/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55989/tests/kraken/driver.html?testName=imaging-darkroom) 10:03:26 INFO - PROCESS | 1938 | RSS: Main: 334077952 10:03:26 INFO - PROCESS | 1938 | 10:03:29 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55989/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55989/tests/kraken/driver.html?testName=imaging-desaturate) 10:03:30 INFO - PROCESS | 1938 | RSS: Main: 335515648 10:03:30 INFO - PROCESS | 1938 | 10:03:32 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55989/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55989/tests/kraken/driver.html?testName=json-parse-financial) 10:03:33 INFO - PROCESS | 1938 | RSS: Main: 336842752 10:03:33 INFO - PROCESS | 1938 | 10:03:35 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55989/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55989/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:03:35 INFO - PROCESS | 1938 | RSS: Main: 314155008 10:03:35 INFO - PROCESS | 1938 | 10:03:37 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55989/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55989/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:03:38 INFO - PROCESS | 1938 | RSS: Main: 322424832 10:03:38 INFO - PROCESS | 1938 | 10:03:40 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55989/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55989/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:03:41 INFO - PROCESS | 1938 | RSS: Main: 319275008 10:03:41 INFO - PROCESS | 1938 | 10:03:43 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55989/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55989/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:03:44 INFO - PROCESS | 1938 | RSS: Main: 327446528 10:03:44 INFO - PROCESS | 1938 | 10:03:47 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55989/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55989/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:03:48 INFO - PROCESS | 1938 | RSS: Main: 329863168 10:03:48 INFO - PROCESS | 1938 | 10:03:49 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:55989/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55989/tests/kraken/driver.html?testName=ai-astar) 10:03:49 INFO - PROCESS | 1938 | RSS: Main: 373391360 10:03:50 INFO - PROCESS | 1938 | 10:03:50 INFO - PROCESS | 1938 | __start_tp_report 10:03:50 INFO - PROCESS | 1938 | _x_x_mozilla_page_load 10:03:50 INFO - PROCESS | 1938 | _x_x_mozilla_page_load_details 10:03:50 INFO - PROCESS | 1938 | |i|pagename|runs| 10:03:50 INFO - PROCESS | 1938 | |0;ai-astar;101;113;114;113;113;113;112;112;112;112 10:03:50 INFO - PROCESS | 1938 | |1;audio-beat-detection;144;113;104;157;107;105;123;106;140;106 10:03:50 INFO - PROCESS | 1938 | |2;audio-dft;195;258;203;182;183;180;182;184;183;183 10:03:50 INFO - PROCESS | 1938 | |3;audio-fft;78;74;58;102;63;57;62;63;81;56 10:03:50 INFO - PROCESS | 1938 | |4;audio-oscillator;83;92;92;71;75;68;95;70;67;101 10:03:50 INFO - PROCESS | 1938 | |5;imaging-gaussian-blur;88;85;93;129;114;83;87;85;87;84 10:03:50 INFO - PROCESS | 1938 | |6;imaging-darkroom;94;93;94;94;94;93;93;105;149;95 10:03:50 INFO - PROCESS | 1938 | |7;imaging-desaturate;83;81;84;85;83;82;84;84;84;84 10:03:50 INFO - PROCESS | 1938 | |8;json-parse-financial;56;56;55;54;55;54;56;56;53;52 10:03:50 INFO - PROCESS | 1938 | |9;json-stringify-tinderbox;63;71;78;74;73;70;70;72;73;71 10:03:50 INFO - PROCESS | 1938 | |10;stanford-crypto-aes;84;82;84;81;86;86;81;82;81;76 10:03:50 INFO - PROCESS | 1938 | |11;stanford-crypto-ccm;145;134;135;134;136;141;144;149;227;141 10:03:50 INFO - PROCESS | 1938 | |12;stanford-crypto-pbkdf2;163;163;161;162;164;161;163;159;160;161 10:03:50 INFO - PROCESS | 1938 | |13;stanford-crypto-sha256-iterative;67;67;66;66;66;67;68;65;66;65 10:03:50 INFO - PROCESS | 1938 | __end_tp_report 10:03:50 INFO - PROCESS | 1938 | __start_cc_report 10:03:50 INFO - PROCESS | 1938 | _x_x_mozilla_cycle_collect,2042 10:03:50 INFO - PROCESS | 1938 | __end_cc_report 10:03:50 INFO - PROCESS | 1938 | __startTimestamp1468515829995__endTimestamp 10:03:50 INFO - PROCESS | 1938 | 10:03:50 INFO - PROCESS | 1938 | ------- Summary: start ------- 10:03:50 INFO - PROCESS | 1938 | Number of tests: 14 10:03:50 INFO - PROCESS | 1938 | 10:03:50 INFO - PROCESS | 1938 | [#0] ai-astar Cycles:10 Average:111.50 Median:113.00 stddev:3.75 (3.3%) stddev-sans-first:0.71 10:03:50 INFO - PROCESS | 1938 | Values: 101.0 113.0 114.0 113.0 113.0 113.0 112.0 112.0 112.0 112.0 10:03:50 INFO - PROCESS | 1938 | 10:03:50 INFO - PROCESS | 1938 | [#1] audio-beat-detection Cycles:10 Average:120.50 Median:118.00 stddev:19.56 (16.6%) stddev-sans-first:18.80 10:03:50 INFO - PROCESS | 1938 | Values: 144.0 113.0 104.0 157.0 107.0 105.0 123.0 106.0 140.0 106.0 10:03:50 INFO - PROCESS | 1938 | 10:03:50 INFO - PROCESS | 1938 | [#2] audio-dft Cycles:10 Average:193.30 Median:183.50 stddev:23.85 (13.0%) stddev-sans-first:25.29 10:03:50 INFO - PROCESS | 1938 | Values: 195.0 258.0 203.0 182.0 183.0 180.0 182.0 184.0 183.0 183.0 10:03:50 INFO - PROCESS | 1938 | 10:03:50 INFO - PROCESS | 1938 | [#3] audio-fft Cycles:10 Average:69.40 Median:63.00 stddev:14.50 (23.0%) stddev-sans-first:15.04 10:03:50 INFO - PROCESS | 1938 | Values: 78.0 74.0 58.0 102.0 63.0 57.0 62.0 63.0 81.0 56.0 10:03:50 INFO - PROCESS | 1938 | 10:03:50 INFO - PROCESS | 1938 | [#4] audio-oscillator Cycles:10 Average:81.40 Median:79.00 stddev:12.75 (16.1%) stddev-sans-first:13.51 10:03:50 INFO - PROCESS | 1938 | Values: 83.0 92.0 92.0 71.0 75.0 68.0 95.0 70.0 67.0 101.0 10:03:50 INFO - PROCESS | 1938 | 10:03:50 INFO - PROCESS | 1938 | [#5] imaging-gaussian-blur Cycles:10 Average:93.50 Median:86.00 stddev:15.42 (17.9%) stddev-sans-first:16.23 10:03:50 INFO - PROCESS | 1938 | Values: 88.0 85.0 93.0 129.0 114.0 83.0 87.0 85.0 87.0 84.0 10:03:50 INFO - PROCESS | 1938 | 10:03:50 INFO - PROCESS | 1938 | [#6] imaging-darkroom Cycles:10 Average:100.40 Median:94.00 stddev:17.45 (18.6%) stddev-sans-first:18.35 10:03:50 INFO - PROCESS | 1938 | Values: 94.0 93.0 94.0 94.0 94.0 93.0 93.0 105.0 149.0 95.0 10:03:50 INFO - PROCESS | 1938 | 10:03:50 INFO - PROCESS | 1938 | [#7] imaging-desaturate Cycles:10 Average:83.40 Median:84.00 stddev:1.17 (1.4%) stddev-sans-first:1.24 10:03:50 INFO - PROCESS | 1938 | Values: 83.0 81.0 84.0 85.0 83.0 82.0 84.0 84.0 84.0 84.0 10:03:50 INFO - PROCESS | 1938 | 10:03:50 INFO - PROCESS | 1938 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.50 stddev:1.42 (2.6%) stddev-sans-first:1.42 10:03:50 INFO - PROCESS | 1938 | Values: 56.0 56.0 55.0 54.0 55.0 54.0 56.0 56.0 53.0 52.0 10:03:50 INFO - PROCESS | 1938 | 10:03:50 INFO - PROCESS | 1938 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:72.50 stddev:3.81 (5.3%) stddev-sans-first:2.51 10:03:50 INFO - PROCESS | 1938 | Values: 63.0 71.0 78.0 74.0 73.0 70.0 70.0 72.0 73.0 71.0 10:03:50 INFO - PROCESS | 1938 | 10:03:50 INFO - PROCESS | 1938 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:83.00 stddev:2.95 (3.5%) stddev-sans-first:3.06 10:03:50 INFO - PROCESS | 1938 | Values: 84.0 82.0 84.0 81.0 86.0 86.0 81.0 82.0 81.0 76.0 10:03:50 INFO - PROCESS | 1938 | 10:03:50 INFO - PROCESS | 1938 | [#11] stanford-crypto-ccm Cycles:10 Average:148.60 Median:142.50 stddev:28.02 (19.7%) stddev-sans-first:29.69 10:03:50 INFO - PROCESS | 1938 | Values: 145.0 134.0 135.0 134.0 136.0 141.0 144.0 149.0 227.0 141.0 10:03:50 INFO - PROCESS | 1938 | 10:03:50 INFO - PROCESS | 1938 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.70 Median:162.50 stddev:1.57 (1.0%) stddev-sans-first:1.59 10:03:50 INFO - PROCESS | 1938 | Values: 163.0 163.0 161.0 162.0 164.0 161.0 163.0 159.0 160.0 161.0 10:03:50 INFO - PROCESS | 1938 | 10:03:50 INFO - PROCESS | 1938 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:0.95 (1.4%) stddev-sans-first:0.97 10:03:50 INFO - PROCESS | 1938 | Values: 67.0 67.0 66.0 66.0 66.0 67.0 68.0 65.0 66.0 65.0 10:03:50 INFO - PROCESS | 1938 | -------- Summary: end -------- 10:03:50 INFO - PROCESS | 1938 | 10:03:50 INFO - TEST-INFO | 1938: exit 0 10:03:50 INFO - TEST-OK | kraken | took 59052ms 10:03:50 INFO - SUITE-END | took 1175s 10:03:51 INFO - Completed test suite (00:19:36) 10:03:51 INFO - javascript benchmark 10:03:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2963.695973771541, 3129.8748050077993, 3300.4719244920816, 3331.6834900684457, 3364.1925937774927, 5473.726313684319, 5499.835004949853, 5550.195221692852, 5572.136318870576, 5626.127950167731, 5528.143137813635, 5580.051391263495, 5673.120666296731, 5708.286464191976, 5720.656760594367, 5020.899582008357, 5047.520485553885, 5104.979004199156, 5126.512981266792, 5270.657407268515, 5158.0, 5179.0, 5190.221466779975, 5212.869678258055, 5224.085784987631, 5045.823396181116, 5088.745562721849, 5098.949010509885, 5142.97428512855, 5143.971205758863, 4437.534058923831, 5053.924191137099, 5056.873578160538, 5132.871678208075, 5140.768665410065, 4212.410262563244, 5023.14606516893, 5049.6212784041345, 5077.669951453144, 5161.5870730341485, 5426.457354264581, 5551.833444996657, 5650.576206784466, 5657.830265092054, 5661.377248502661, 5940.702964851784, 6069.393060693939, 6088.883356155857, 6123.6938153092615, 6136.754529818846, 6147.385261473862, 6147.969260153715, 6206.2862770781485, 6219.626822390625, 6256.7810126645345, 5372.355317361941, 5660.915086273709, 5709.257796486471, 5716.799912003102, 5737.913931291034, 6175.876482470415, 6222.62664240147, 6230.221222347207, 6270.216222972129, 6281.623102613858, 6001.609895356788, 6012.518998480111, 6046.879062418727, 6047.183630209954, 6054.727537260834, 5946.375630558816, 5997.670128142994, 6062.818115456544, 6147.600405973598, 6158.230221222348, 6011.248593925759, 6047.0929360596265, 6068.908966365508, 6079.392060793885, 6085.117657939574, 5067.6199285053035, 6067.302260240041, 6097.786577469856, 6118.663473508911, 6129.264488261437, 4709.317149013443, 4742.857714268577, 4788.784504697297, 4802.759862006886, 4807.0, 4334.371516130206, 4347.260965635849, 4356.978215108904, 4377.3433984901885, 4390.670699697535, 5564.165375193672, 5568.359638641527, 5569.053260945649, 5574.386817450078, 5585.413531579207, 6122.855605810045, 6195.19462469876, 6198.132261483396, 6209.410106039919, 6209.441150296405, 5587.273654424899, 5617.101263797855, 5620.409856965041, 5660.688662123623, 5661.433856614306, 5181.248718935733, 5253.658512196695, 5294.258803767476, 5301.6818990860675, 5321.813736519203, 6160.860240855366, 6207.0, 6226.128342032029, 6231.283402408691, 6234.189555357865, 6093.573449858467, 6157.507399407947, 6160.753569857335, 6185.381461853958, 6197.96901015492, 6105.126180223362, 6175.104609831459, 6215.378462153749, 6225.782097626561, 6273.96863015682, 5926.703664816828, 5951.196588460675, 5953.1367951647635, 5964.582479226413, 5976.342602313655, 5059.342285502861, 6048.909266361096, 6076.756929722938, 6083.391660833881, 6095.451409373178, 5141.974290128525, 5920.111983202554, 5955.255593050869, 6039.305479869696, 6054.0918862169265, 5006.374203224597, 5535.446455354432, 5964.642121472725, 5983.551233657406, 5997.760089596367, 5209.270702101785, 5551.667599775944, 5636.1827535008015, 5827.650340979555, 5831.650100993954, 5829.038208695518, 5901.026330655393, 5916.615419997686, 5922.703864806828, 5923.644581325134, 5732.369439361583, 5741.3397459291045, 5743.6840973745175, 5750.281214848144, 5755.8561035974435], "value": 5546.675648172496, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.67721949074627, 476.7640018190992, 484.7479310758406, 515.785948831235, 569.7407679505825, 36059.0, 42649.36025959613, 42725.78637106818, 42860.78569607155, 45078.0, 43280.56719432805, 43585.0, 43713.0, 43738.34392484107, 43768.78115609418, 8861.202491775739, 8938.597763100644, 9774.902250977506, 9950.800983980316, 9956.054174853414, 12007.3395963222, 12173.573924912629, 12174.269543827355, 12357.073219508527, 12376.566820161295, 12123.575674851381, 12219.266843989344, 12221.144519883612, 12238.082143839203, 12246.448909799035, 8914.465132092095, 8999.910000899972, 9036.186743193079, 9145.862812057825, 12107.03143748498, 11840.881591184065, 12060.64817360615, 12062.0, 12287.754244915051, 12292.016638668974, 41491.585084149076, 41921.0, 41935.370969435484, 41973.1605367894, 41979.790101049606, 3600.6579374959597, 3676.7058635309113, 3702.500162478058, 4115.053537686319, 4134.751914885086, 26794.062207822633, 31762.0, 32753.67246327554, 32950.0, 33169.0, 3284.293876816496, 3319.717823984964, 3326.334733053399, 3342.832858357097, 3375.3755555222115, 447657.0, 448753.0, 452972.0, 453123.0, 453759.0, 53905.0, 55479.335619931466, 63793.30170332141, 64147.679261604324, 64404.0, 57223.0, 65514.67242663851, 67104.32895671079, 67625.3237467619, 67651.0, 16531.66936661284, 16704.331826726793, 16723.247453864606, 16748.162591870358, 16756.245968931427, 10647.94676026615, 10655.893441065646, 10761.623343183108, 10762.946185269024, 11063.889361106447, 16505.504834854975, 18175.81824181741, 18627.627447450974, 18632.97518636488, 19066.2373505061, 7101.762944672411, 7167.283271672895, 7188.101487314086, 7225.89161162583, 7254.8911766323545, 916.867054277126, 970.4807927758616, 1037.8235699930879, 1095.8794532601407, 1105.7401510644993, 29286.151305481148, 30912.845435772677, 31275.687243127733, 31345.686543134732, 31705.52441713376, 1043.2071625564474, 1046.006471979501, 1052.0, 1067.2955849139687, 1144.164759725405, 14009.929950350386, 15538.456154034555, 15785.526434206991, 16154.919225403797, 16498.83501164997, 663706.0, 664715.0, 666779.0, 667372.0, 667717.0, 60366.0, 62177.0, 62182.68908655518, 62187.06719399212, 62216.37783622197, 1290.9677258068436, 1332.3937608387967, 1409.0770545292849, 1429.6497358147112, 1459.3214155417782, 27966.720332796413, 28753.85623071955, 28914.27714307159, 29582.556261656522, 29586.852065739535, 52743.20885186802, 53300.73349633227, 53448.198277026655, 53493.930121396574, 53518.46481535135, 527329.0, 541099.0, 542219.0, 543761.0, 544723.0, 36007.0, 36826.26347473089, 41755.7912210437, 42721.572784273005, 43813.0, 48106.51893481021, 49010.50989490059, 49239.50760492349, 50165.74917125391, 50546.989060219326, 38990.6100938987, 40231.396529052676, 40803.939704522876, 41166.0, 41620.37569436521, 39353.21293574055, 39494.2101157981, 39902.0, 40351.0, 40655.0, 38751.0, 39459.0, 39559.208815824095, 39809.20381592294, 39818.004549886486, 33619.327613448084, 35153.0, 35411.33982668892, 35511.46732798959, 35921.82039089892, 25802.61296080523, 26001.739982600688, 26117.608235876858, 26540.84047652207, 26920.0], "value": 20868.771371269948, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.18360678635753, 356.74314493564657, 373.41374042752875, 386.6095243803759, 395.3950455802623, 15299.0, 15784.947956765618, 20737.963101844904, 21226.46933826655, 21687.891560542215, 19637.607247855034, 20230.797692023076, 20663.896680516576, 20951.895240523776, 20960.89519552404, 5881.5294776417995, 6049.9697501512655, 6111.3888611138755, 6119.724612392453, 6126.0504621783475, 4208.852690155845, 4659.998100408428, 6044.425779550936, 6717.26110127888, 6799.558028728142, 6689.665516724169, 6706.5976041437425, 6715.227748808877, 6719.8656026879435, 6727.899081513757, 6440.5491615586925, 6701.195856497203, 6703.195616525999, 6704.765333213314, 6706.429953453938, 3738.327101121797, 5443.646162999432, 5755.827325180251, 5762.827115186551, 5797.8550536236935, 23757.812109394636, 24095.759042409525, 25744.87127564369, 25871.0, 26190.0, 2229.6321107017325, 2309.6766452696634, 2372.454335502844, 2513.1832154549843, 2532.3669082729316, 14440.55594440558, 14514.201718905457, 15513.92243038789, 16354.672906541802, 16452.506424807274, 1715.7389318850635, 1868.953276168093, 1946.6593346164507, 1974.3089918528544, 2006.8695534790193, 119244.40377798142, 121114.78885211212, 122020.0, 122500.0, 122881.99999999911, 9690.95154524223, 10457.84313235287, 10687.679369618923, 11059.557617695202, 11154.442277886073, 12604.55884044054, 12941.741165176643, 13322.73354532904, 13330.933345333406, 13632.591022269347, 8710.869336959951, 8735.213830755263, 8789.472631642122, 8856.690015849543, 8877.822443551093, 8842.425242359226, 8857.3799834011, 8864.8670269946, 8987.46075235488, 9002.459852408876, 5626.268585083994, 6545.280019197884, 6636.369544893228, 6641.634710090992, 6694.49791265657, 1838.4576549917806, 1873.7376767252595, 1875.2873907914905, 1878.9624207515774, 1880.9623807523774, 776.7397921696295, 939.8449255872841, 941.1136016150298, 971.1647982734843, 1000.9149222316184, 21242.468938276666, 21876.671849922262, 21882.23412180566, 21895.12419503234, 21912.013959371867, 973.8052389522123, 1004.4073996342194, 1007.2948935745007, 1024.6772266735954, 1091.8908109189017, 11018.669439916815, 11562.364069976233, 11585.246958947642, 11700.824487632692, 11734.178607497564, 554882.0, 556195.0, 562921.0, 586340.0, 586908.0, 73415.0, 73933.0, 114721.27918081236, 118787.8121218794, 121560.39219804021, 862.5040601654074, 1031.2214278219897, 1053.8577292065627, 1055.3878750324948, 1070.5129166229347, 18630.44108676742, 19409.029548522798, 19435.3197638082, 19473.221071156993, 19580.412587622395, 48827.51172488372, 56816.71591642015, 59463.51341216504, 60849.69575152096, 61364.38635613587, 62545.37454625578, 86827.0, 88644.55677721571, 89876.0, 91301.0, 7971.601419929097, 8128.796780080545, 8166.714165004197, 8448.408611397143, 8461.111583283666, 9334.673286435214, 12557.74884502323, 13429.395677194354, 13729.45082196701, 13885.791713124514, 10188.796224075626, 11439.199256051996, 11878.227915185485, 11895.405229738306, 11949.581764638544, 11479.0, 11517.827232591686, 11566.65300041, 11896.22674526153, 11904.0, 11718.824217636571, 11744.471498782746, 11927.522899084286, 12027.699307517381, 12029.518819247132, 7120.216776154515, 8146.796330091795, 8270.338372930031, 8349.248567628943, 8473.703420380258, 9649.0, 11125.054370378284, 11616.244944078608, 11644.475998579916, 11756.0], "value": 9876.098495611035, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.8167238259039, 1428.3501007041793, 1532.7394342961695, 1717.5877789330557, 1965.3121407507374, 7786.377089832814, 8554.529500877457, 8785.736427907157, 8795.648174073045, 8799.648014079445, 8403.159684031594, 8725.040245572982, 8799.164079412461, 8892.91107088929, 8903.0, 2101.7162683037823, 2179.1501314487364, 2344.7069116360453, 2392.4616961183724, 2412.2401443545227, 2423.3578101803037, 2437.19572541061, 2439.817013723969, 2440.389902524369, 2442.511497700458, 2381.8570885746826, 2394.976050239493, 2422.878856057199, 2424.624183251599, 2424.8908799103992, 1667.674803413341, 2309.3533810533077, 2364.042562762084, 2370.3244575295957, 2370.8577485350934, 2392.413858604653, 2418.238254949685, 2418.5888398972215, 2419.6128619420806, 2421.406755344934, 8963.731088067303, 9040.276777857756, 9047.68333108338, 9048.547572621343, 9053.185213330766, 5164.690118592859, 5181.818636347747, 5218.843434696965, 5288.206768984644, 5299.364076310829, 3421.469672200813, 3553.928921421557, 3683.4658974448553, 3690.5940346561856, 3704.0554658562164, 1084.0839490630406, 1101.1631160318136, 1109.1404161774608, 1129.7571022230177, 1130.8699499557574, 11824.9948754356, 12074.577389791402, 12078.15452918296, 12100.515979360815, 12101.636950891487, 3933.3116704577155, 4627.583517483443, 4876.024795041006, 4876.244182151772, 4879.170541008037, 10377.273590848568, 10380.896191037993, 10382.221333399873, 10385.740356491147, 10394.740131496774, 5421.4578542146255, 5429.077056900336, 5432.185172224119, 5432.728363581884, 5441.83674489766, 3776.3202623527477, 4551.40831691878, 4777.116233496814, 4881.731504767272, 4892.730899800474, 4051.716379853441, 4092.7339722917914, 4123.670106391482, 4182.728122672017, 4186.665066794649, 1085.880553139154, 1209.679434949739, 1286.3761075878192, 1336.3117994232978, 1346.9057165998286, 805.3114587028124, 806.3911746631256, 918.9632414703337, 977.4379731654327, 1016.8560369584218, 6135.202423684982, 6140.662263575547, 6150.7232174552255, 6340.270868850049, 6349.6507692077375, 967.6274634265858, 983.3755565215996, 996.9202463802878, 1016.242899040205, 1019.1541020952592, 3934.7048971327263, 4069.369247766601, 4096.0, 4124.319487284564, 4169.645580125723, 10741.570337186582, 10956.34261944286, 11157.553697852158, 11164.776704466027, 11172.217944743952, 11097.944510277397, 11105.722356941142, 11111.333320000826, 11130.053945414731, 11142.721431964266, 7199.280071992759, 8242.505449673039, 8247.010358757108, 8339.416240863202, 8507.65969361231, 6094.512439004958, 6128.356522565245, 6153.107799368977, 6246.0, 6315.526335524946, 9962.302638815214, 10125.0, 10429.58281668755, 10441.895581044093, 10460.0, 10256.0, 10573.04842564173, 10598.576056957636, 10618.946905265424, 10654.414007229676, 7768.223177682186, 7769.339606133542, 7818.452708310591, 7857.332126769289, 7886.408519361185, 8434.957825210835, 8533.232009118961, 8538.530380829114, 8541.444806087584, 8559.61481733336, 7484.850302994018, 7646.502977306457, 7667.233276672511, 7798.688052477837, 7804.453688241769, 6646.302138275605, 7539.736109236151, 7550.282723141293, 7562.470627056054, 7599.620018998918, 5537.501624853782, 6260.280067792262, 7260.0, 7260.782176534713, 7265.782026539001, 5107.259447380184, 5160.716160611202, 5206.01085793698, 5209.53114219722, 5331.280277162688, 4869.123557759637, 5118.846434606968, 5170.121079416584, 5259.368875734936, 5353.946460535501], "value": 4672.738115694096, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.45984148216166, 303.8101186758276, 317.45081009852953, 319.1940350614699, 321.4663658327177, 4830.647418722754, 5024.24636304554, 5153.330067091278, 5213.9478605213935, 5218.295530103435, 4337.4795024597015, 4895.437024742156, 5110.667806592569, 5153.252778347138, 5184.611154163434, 3915.647591716745, 3970.54338751043, 3973.1457736586626, 3989.760614363133, 4006.8597599084037, 3968.6825053995753, 3985.0, 3985.4293980382636, 4009.4787677602017, 4009.777018009506, 3361.411752943225, 3404.285100128976, 3874.9031274218087, 3969.920601587981, 3976.741511801724, 3109.7667674924246, 3582.4089025310923, 3684.6499582539614, 3890.2414029264305, 3930.64624183822, 3452.5486904297977, 3460.5501284832812, 3462.7056700180515, 3475.8088305143206, 3486.6164721880573, 4866.294387313855, 5053.9241911371355, 5097.317885097908, 5122.231665250205, 5135.64050516464, 4513.7275475280485, 4530.682852200347, 4539.682222244444, 4557.293619488984, 4569.771511424448, 4255.91488170238, 4348.977990172307, 5384.788422604922, 5391.676499410009, 5415.377231618336, 3397.83010849459, 3804.885853424402, 3835.42468629705, 3846.769193848378, 3852.4028775539837, 4756.548127927841, 5285.709285989269, 5534.0315444797, 5671.7731290748325, 5674.631148975344, 3327.7005069543375, 3399.7450191235757, 3600.3519366513756, 3765.378712512405, 3795.4306853972125, 3265.722413594871, 3872.8838134856, 3876.7286289959434, 3881.3595756700392, 3892.2484881803302, 3790.938537209586, 4230.534641189467, 4460.018795864969, 4481.35020422037, 4498.065831692338, 4496.752678602643, 4901.828436004723, 5104.3364362633365, 5189.247559103985, 5432.674039557639, 1196.5871774237874, 1212.3574505511917, 1230.5939040116739, 1269.073576289314, 1310.8099325597925, 822.8642274024838, 858.6694122762781, 883.3330835219497, 885.3271513649672, 887.3078998381272, 4360.324149756792, 5023.271625614266, 5044.571211446994, 5059.266406371129, 5072.695638261716, 4691.319758634979, 5564.944350556523, 5566.666000040011, 5574.60977731563, 5599.356074051536, 4809.95190048102, 5150.974245128826, 5180.896382072413, 5194.545627157898, 5219.947800522022, 2730.836149830977, 2902.6371703537056, 2936.8972085977316, 2969.3615872587065, 2984.5224764038085, 4890.021995600894, 5432.348118225838, 5433.728313584305, 5490.396056433869, 5800.767969281266, 4860.618297034498, 5747.885042299131, 5770.365259821417, 5789.652620842763, 5803.82588522345, 5213.530782229542, 5775.826725198251, 5820.505257053198, 5823.56323275756, 5827.766889324549, 5153.665011774223, 5192.351900530832, 5446.556662484524, 5650.491455769001, 5658.717064146858, 5533.391326954113, 5546.528545073553, 5566.916496252478, 5570.493278024824, 5581.5813813963305, 5358.035553600389, 5408.78364865401, 5511.559075274049, 5795.971020144872, 5840.357560668408, 3328.683775041367, 3456.3087382523095, 3571.696330839277, 3678.429843374281, 3798.1833905710405, 4462.687611867138, 4736.976315118402, 5622.128570071645, 5673.071155807045, 5681.346645135863, 4640.8137317228175, 4756.9524304756505, 4896.36347274852, 5435.239066530768, 5544.500994910478, 4802.5112215212, 5438.918416223681, 5450.563954883679, 5464.726763661722, 5467.480589344006, 4170.603792639695, 4340.8697739067875, 5237.685738855528, 5289.646107482134, 5381.058314794817, 5446.455354464521, 5481.698506582176, 5487.451254874481, 5492.105223697818, 5515.61390702647, 4286.80709368073, 4654.672663668166, 4952.702837829742, 5342.679439233659, 5390.434004429635], "value": 3956.7327827522977, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.1856923822223, 746.5371469688793, 752.6763491698567, 796.219704689404, 874.9125087491252, 15035.398584056638, 16301.918490407532, 16317.918410407932, 16324.755128673065, 16333.020018798881, 14512.927435362808, 14625.92687036566, 14741.115533068023, 14777.0, 14948.327325270362, 716.4089626058496, 886.8359353519603, 913.4017218721748, 913.4930113786845, 915.9129882661136, 726.687524364523, 880.9427387219843, 921.8202450522151, 923.3305853256403, 923.7044145873323, 967.351874244258, 967.8499832525935, 968.1915600473613, 970.2916870684404, 970.8106919150769, 736.9594672293022, 923.4920793563513, 954.7708549948032, 956.9999350678557, 958.650092716157, 716.1728203924423, 716.9211386747454, 717.1716667249335, 717.2540557819862, 723.2658851265969, 14995.700085998327, 15044.548663540112, 15064.171470569114, 15067.924660376737, 15099.320530576151, 13467.326633668277, 13945.930270348685, 14043.929780351033, 14085.225312607803, 14224.644383890382, 12712.491500339975, 12720.236785792882, 12838.229706217657, 12996.220226786423, 13180.604581862559, 4516.16450956574, 4537.342085397599, 4563.110193512266, 4686.906261874778, 4729.101470720552, 114947.0, 117090.0, 117426.0, 118088.40955795166, 119881.40059299735, 11107.38909359993, 11114.0, 11129.054030407506, 11204.271722338022, 11319.490622921989, 21482.355529334145, 21587.244446444613, 21634.78365216359, 21674.02466888994, 22061.558768824856, 7993.440459167802, 8004.599770011476, 8083.838323233503, 8213.79465513355, 8265.0, 4468.017036252019, 4563.38394316763, 4598.632109431237, 4784.6889952153, 4838.588719958843, 3199.632042315117, 3356.009977056774, 3356.9328613427597, 3384.6446123157207, 3384.9845046485957, 686.2588404523203, 693.6843736100062, 728.5555810955314, 747.872861613527, 748.5246868238708, 648.2836465705328, 651.6188030002406, 678.3860606151441, 697.6197972105215, 710.8542748736496, 9536.52317384128, 9560.665376711782, 9703.563339649732, 9771.31600787938, 9805.970373110864, 677.8881484554993, 688.9827754306082, 695.5027155583782, 697.9916559628019, 708.6031822179596, 4111.054457474797, 4570.588647021784, 4744.501827308152, 4747.192977193886, 4748.596369308579, 101879.98120018853, 131056.3447182758, 154624.0, 154760.0, 171856.0, 145234.2738286302, 146825.0, 146988.26505867616, 147496.0, 156905.0, 116019.41990289994, 120635.39682301707, 120691.39654301848, 121551.39224303822, 121630.39184804019, 9122.407043542149, 9143.268538517035, 9187.540622968958, 9228.292944648116, 9473.289503287418, 66249.66875165593, 66552.6672366635, 67318.0, 67803.0, 67945.66027169832, 89405.0, 95190.52404737932, 99383.50308248412, 102026.48986755019, 103901.0, 13949.16305021702, 14019.92990035084, 14090.436382544583, 14331.0, 14421.927890360901, 14779.92610036943, 14989.850101498845, 15134.0, 15680.215989200722, 16348.509544713677, 13879.167249965034, 14214.360353783897, 14239.35922883452, 14479.710405792035, 14640.487582934973, 14266.358013889192, 14284.928575357057, 14306.785398218826, 14317.355718992461, 14347.92826035905, 12764.68088297763, 13144.0, 13257.469701211843, 13297.601071967856, 13324.400401982128, 11242.606508772154, 11631.825522616999, 11647.76704465923, 11783.293002419883, 11892.76214475723, 10031.799364012533, 10625.724913010488, 11406.714832129263, 11461.426928653367, 11542.422878856192], "value": 8171.1675315498915, "unit": "score"}], "name": "dromaeo_css", "value": 7462.6461283494, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 114.0, 113.0, 113.0, 113.0, 112.0, 112.0, 112.0, 112.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 113.0, 104.0, 157.0, 107.0, 105.0, 123.0, 106.0, 140.0, 106.0], "value": 120.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 258.0, 203.0, 182.0, 183.0, 180.0, 182.0, 184.0, 183.0, 183.0], "value": 193.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 58.0, 102.0, 63.0, 57.0, 62.0, 63.0, 81.0, 56.0], "value": 69.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 92.0, 92.0, 71.0, 75.0, 68.0, 95.0, 70.0, 67.0, 101.0], "value": 81.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 93.0, 129.0, 114.0, 83.0, 87.0, 85.0, 87.0, 84.0], "value": 93.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 94.0, 93.0, 93.0, 105.0, 149.0, 95.0], "value": 100.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 81.0, 84.0, 85.0, 83.0, 82.0, 84.0, 84.0, 84.0, 84.0], "value": 83.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 54.0, 55.0, 54.0, 56.0, 56.0, 53.0, 52.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 71.0, 78.0, 74.0, 73.0, 70.0, 70.0, 72.0, 73.0, 71.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 84.0, 81.0, 86.0, 86.0, 81.0, 82.0, 81.0, 76.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 134.0, 135.0, 134.0, 136.0, 141.0, 144.0, 149.0, 227.0, 141.0], "value": 148.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 161.0, 162.0, 164.0, 161.0, 163.0, 159.0, 160.0, 161.0], "value": 161.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 66.0, 66.0, 67.0, 68.0, 65.0, 66.0, 65.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1438.5, "alertThreshold": 2.0}]} 10:03:51 INFO - Return code: 0 10:03:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:03:51 INFO - # TBPL SUCCESS # 10:03:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:03:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:03:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:03:51 INFO - 2016-07-14 10:03:51.341 screenresolution[1942:21662] starting screenresolution argv=screenresolution get 10:03:51 INFO - 2016-07-14 10:03:51.344 screenresolution[1942:21662] Display 0: 1600x1200x32@60 10:03:51 INFO - 2016-07-14 10:03:51.350 screenresolution[1943:21665] starting screenresolution argv=screenresolution list 10:03:51 INFO - Available Modes on Display 0 10:03:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:03:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:03:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:03:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:03:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:03:51 INFO - Intel Iris: 10:03:51 INFO - Chipset Model: Intel Iris 10:03:51 INFO - Type: GPU 10:03:51 INFO - Bus: Built-In 10:03:51 INFO - VRAM (Dynamic, Max): 1536 MB 10:03:51 INFO - Vendor: Intel (0x8086) 10:03:51 INFO - Device ID: 0x0a2e 10:03:51 INFO - Revision ID: 0x0009 10:03:51 INFO - Displays: 10:03:51 INFO - Display: 10:03:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:03:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:03:51 INFO - Display Serial Number: 1600x1200 60 10:03:51 INFO - Main Display: Yes 10:03:51 INFO - Mirror: Off 10:03:51 INFO - Online: Yes 10:03:51 INFO - Rotation: Supported 10:03:51 INFO - Return code: 0 10:03:51 INFO - Running post-action listener: _resource_record_post_action 10:03:51 INFO - ##### 10:03:51 INFO - ##### Finished run-tests step (success) 10:03:51 INFO - ##### 10:03:51 INFO - Running post-run listener: _resource_record_post_run 10:03:51 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 991232; Write bytes: 704985600; Read time: 10; Write time: 2347 10:03:51 INFO - TinderboxPrint: CPU usage
53.5% 10:03:51 INFO - TinderboxPrint: I/O read bytes / time
991,232 / 10 10:03:51 INFO - TinderboxPrint: I/O write bytes / time
704,985,600 / 2,347 10:03:51 INFO - TinderboxPrint: CPU idle
2,253.6 (46.5%) 10:03:51 INFO - TinderboxPrint: CPU system
967.5 (20.0%) 10:03:51 INFO - TinderboxPrint: CPU user
1,624.5 (33.5%) 10:03:51 INFO - TinderboxPrint: Swap in / out
407,891,968 / 0 10:03:51 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392407552; Write bytes: 388482560; Read time: 29094; Write time: 815 10:03:51 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 991232; Write bytes: 293434368; Read time: 10; Write time: 1484 10:03:52 INFO - Running post-run listener: _upload_blobber_files 10:03:52 INFO - Blob upload gear active. 10:03:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:03:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:03:52 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'] 10:03:52 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 10:03:52 INFO - (blobuploader) - INFO - Open directory for files ... 10:03:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:03:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:03:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:03:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:03:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:03:53 INFO - (blobuploader) - INFO - Done attempting. 10:03:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:03:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:03:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:03:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:03:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:03:54 INFO - (blobuploader) - INFO - Done attempting. 10:03:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:03:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:03:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:03:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:03:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:03:57 INFO - (blobuploader) - INFO - Done attempting. 10:03:57 INFO - (blobuploader) - INFO - Iteration through files over. 10:03:57 INFO - Return code: 0 10:03:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:03:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:03:57 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/394f31cc076848ff928ca684d71556781538df1f90b0ab2b9c28a22b16bbd109d13ef12ac3fa3d850603123cbc190a8563b27bc91af10f80f6325d10d938ab24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a47b133206d21f79de4ac13a3409a491bb4ad5e6fa16ca70f69fb5da7f20c47817d455004b43cfc59d13d1c6e4339df68ac35c3e9f54c2d9956df97fc3df114d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/927deda68330403884065ddd6f7161c9dcd02431acab2dc1493f1985fb7a071d79c6c897dad10043f0249d1719ea41eae8ac0136c6d871ea3459859db7c1538d"} 10:03:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:03:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:03:57 INFO - Contents: 10:03:57 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/394f31cc076848ff928ca684d71556781538df1f90b0ab2b9c28a22b16bbd109d13ef12ac3fa3d850603123cbc190a8563b27bc91af10f80f6325d10d938ab24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a47b133206d21f79de4ac13a3409a491bb4ad5e6fa16ca70f69fb5da7f20c47817d455004b43cfc59d13d1c6e4339df68ac35c3e9f54c2d9956df97fc3df114d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/927deda68330403884065ddd6f7161c9dcd02431acab2dc1493f1985fb7a071d79c6c897dad10043f0249d1719ea41eae8ac0136c6d871ea3459859db7c1538d"} 10:03:57 INFO - Running post-run listener: copy_logs_to_upload_dir 10:03:57 INFO - Copying logs to upload dir... 10:03:57 INFO - mkdir: /builds/slave/test/build/upload/logs 10:03:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.547249 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-14 10:03:57.287944) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-14 10:03:57.291451) ========= 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.BTLrpfUGr5/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.Ad38fgbDsf/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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/394f31cc076848ff928ca684d71556781538df1f90b0ab2b9c28a22b16bbd109d13ef12ac3fa3d850603123cbc190a8563b27bc91af10f80f6325d10d938ab24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a47b133206d21f79de4ac13a3409a491bb4ad5e6fa16ca70f69fb5da7f20c47817d455004b43cfc59d13d1c6e4339df68ac35c3e9f54c2d9956df97fc3df114d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/927deda68330403884065ddd6f7161c9dcd02431acab2dc1493f1985fb7a071d79c6c897dad10043f0249d1719ea41eae8ac0136c6d871ea3459859db7c1538d"} build_url:https://queue.taskcluster.net/v1/task/H6K75C6nTluH1OPiZ6n88A/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010982 build_url: 'https://queue.taskcluster.net/v1/task/H6K75C6nTluH1OPiZ6n88A/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/394f31cc076848ff928ca684d71556781538df1f90b0ab2b9c28a22b16bbd109d13ef12ac3fa3d850603123cbc190a8563b27bc91af10f80f6325d10d938ab24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a47b133206d21f79de4ac13a3409a491bb4ad5e6fa16ca70f69fb5da7f20c47817d455004b43cfc59d13d1c6e4339df68ac35c3e9f54c2d9956df97fc3df114d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/927deda68330403884065ddd6f7161c9dcd02431acab2dc1493f1985fb7a071d79c6c897dad10043f0249d1719ea41eae8ac0136c6d871ea3459859db7c1538d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-14 10:03:57.326600) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 10:03:57.327350) ========= 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.BTLrpfUGr5/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.Ad38fgbDsf/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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.005025 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 10:03:57.379520) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-14 10:03:57.380223) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-14 10:03:57.380923) ========= ========= Total master_lag: 2.32 =========