builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0226 starttime: 1467290763.81 results: success (0) buildid: 20160630035327 builduid: 32072485d7a5448eac72d3a088240fb6 revision: 7b4e8a8e4f0b3a13e0862d8b54f7e9967069b6d9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-30 05:46:03.815230) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-30 05:46:03.815671) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-30 05:46:03.815939) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-30 05:46:03.924042) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 05:46:03.924288) ========= 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.ITFhZSXBLk/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.pgryuwUo6F/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-30 05:46:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 190M=0s 2016-06-30 05:46:04 (190 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117347 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 05:46:04.086409) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 05:46:04.086678) ========= 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.ITFhZSXBLk/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.pgryuwUo6F/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.026306 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 05:46:04.137924) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-30 05:46:04.138244) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 7b4e8a8e4f0b3a13e0862d8b54f7e9967069b6d9 --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 7b4e8a8e4f0b3a13e0862d8b54f7e9967069b6d9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ITFhZSXBLk/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.pgryuwUo6F/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-30 05:46:04,231 truncating revision to first 12 chars 2016-06-30 05:46:04,231 Setting DEBUG logging. 2016-06-30 05:46:04,231 attempt 1/10 2016-06-30 05:46:04,231 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/7b4e8a8e4f0b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-30 05:46:05,470 unpacking tar archive at: fx-team-7b4e8a8e4f0b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.502921 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-30 05:46:05.672819) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-30 05:46:05.673318) ========= script_repo_revision: 7b4e8a8e4f0b3a13e0862d8b54f7e9967069b6d9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-30 05:46:05.673802) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-30 05:46:05.674198) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-30 05:46:05.699276) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-06-30 05:46:05.699640) ========= /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.ITFhZSXBLk/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.pgryuwUo6F/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:46:05 INFO - MultiFileLogger online at 20160630 05:46:05 in /builds/slave/test 05:46:05 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 05:46:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:46:05 INFO - {'append_to_log': False, 05:46:05 INFO - 'base_work_dir': '/builds/slave/test', 05:46:05 INFO - 'blob_upload_branch': 'Fx-Team', 05:46:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:46:05 INFO - 'branch': 'Fx-Team', 05:46:05 INFO - 'buildbot_json_path': 'buildprops.json', 05:46:05 INFO - 'config_files': ('talos/mac_config.py',), 05:46:05 INFO - 'default_actions': ('clobber', 05:46:05 INFO - 'read-buildbot-config', 05:46:05 INFO - 'download-and-extract', 05:46:05 INFO - 'populate-webroot', 05:46:05 INFO - 'create-virtualenv', 05:46:05 INFO - 'install', 05:46:05 INFO - 'run-tests'), 05:46:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:46:05 INFO - 'download_minidump_stackwalk': True, 05:46:05 INFO - 'download_symbols': 'ondemand', 05:46:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:46:05 INFO - 'tooltool.py': '/tools/tooltool.py', 05:46:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:46:05 INFO - '/tools/misc-python/virtualenv.py')}, 05:46:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:46:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:46:05 INFO - 'installer_path': 'installer.exe', 05:46:05 INFO - 'log_level': 'info', 05:46:05 INFO - 'log_name': 'talos', 05:46:05 INFO - 'log_to_console': True, 05:46:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:46:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:46:05 INFO - 'opt_config_files': (), 05:46:05 INFO - 'pip_index': False, 05:46:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:46:05 INFO - 'cmd': ('bash', 05:46:05 INFO - '-c', 05:46:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:46:05 INFO - 'enabled': True, 05:46:05 INFO - 'halt_on_failure': False, 05:46:05 INFO - 'name': 'check_screen_resolution'},), 05:46:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:46:05 INFO - 'cmd': ('bash', 05:46:05 INFO - '-c', 05:46:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:46:05 INFO - 'enabled': True, 05:46:05 INFO - 'halt_on_failure': False, 05:46:05 INFO - 'name': 'check_screen_resolution'},), 05:46:05 INFO - 'run_cmd_checks_enabled': True, 05:46:05 INFO - 'sps_profile': False, 05:46:05 INFO - 'sps_profile_interval': 0, 05:46:05 INFO - 'suite': 'dromaeojs', 05:46:05 INFO - 'system_bits': '32', 05:46:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:46:05 INFO - 'title': 't-yosemite-r7-0226', 05:46:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:46:05 INFO - 'use_talos_json': True, 05:46:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:46:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:46:05 INFO - 'work_dir': 'build'} 05:46:05 INFO - ##### 05:46:05 INFO - ##### Running clobber step. 05:46:05 INFO - ##### 05:46:05 INFO - Running pre-action listener: _resource_record_pre_action 05:46:05 INFO - Running main action method: clobber 05:46:05 INFO - rmtree: /builds/slave/test/build 05:46:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:46:07 INFO - Running post-action listener: _resource_record_post_action 05:46:07 INFO - ##### 05:46:07 INFO - ##### Finished clobber step (success) 05:46:07 INFO - ##### 05:46:07 INFO - ##### 05:46:07 INFO - ##### Running read-buildbot-config step. 05:46:07 INFO - ##### 05:46:07 INFO - Running pre-action listener: _resource_record_pre_action 05:46:07 INFO - Running main action method: read_buildbot_config 05:46:07 INFO - Using buildbot properties: 05:46:07 INFO - { 05:46:07 INFO - "project": "", 05:46:07 INFO - "product": "firefox", 05:46:07 INFO - "script_repo_revision": "production", 05:46:07 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:46:07 INFO - "repository": "", 05:46:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:46:07 INFO - "buildid": "20160630035327", 05:46:07 INFO - "pgo_build": "False", 05:46:07 INFO - "basedir": "/builds/slave/test", 05:46:07 INFO - "buildnumber": 864, 05:46:07 INFO - "slavename": "t-yosemite-r7-0226", 05:46:07 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:46:07 INFO - "platform": "yosemite_r7", 05:46:07 INFO - "scheduler": "tests-fx-team-macosx64-talos", 05:46:07 INFO - "branch": "fx-team", 05:46:07 INFO - "repo_path": "integration/fx-team", 05:46:07 INFO - "revision": "7b4e8a8e4f0b3a13e0862d8b54f7e9967069b6d9", 05:46:07 INFO - "stage_platform": "macosx64", 05:46:07 INFO - "builduid": "32072485d7a5448eac72d3a088240fb6", 05:46:07 INFO - "slavebuilddir": "test" 05:46:07 INFO - } 05:46:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 05:46:07 INFO - Running post-action listener: _resource_record_post_action 05:46:07 INFO - ##### 05:46:07 INFO - ##### Finished read-buildbot-config step (success) 05:46:07 INFO - ##### 05:46:07 INFO - ##### 05:46:07 INFO - ##### Running download-and-extract step. 05:46:07 INFO - ##### 05:46:07 INFO - Running pre-action listener: _resource_record_pre_action 05:46:07 INFO - Running main action method: download_and_extract 05:46:07 INFO - mkdir: /builds/slave/test/build/tests 05:46:07 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')]} 05:46:07 INFO - https://queue.taskcluster.net/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:46:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:46:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 05:46:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/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 05:46:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/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 05:46:09 INFO - Downloaded 1199 bytes. 05:46:09 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 05:46:09 INFO - Using the following test package requirements: 05:46:09 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:46:09 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:46:09 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 05:46:09 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:46:09 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 05:46:09 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:46:09 INFO - u'jsshell-mac.zip'], 05:46:09 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:46:09 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 05:46:09 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 05:46:09 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:46:09 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 05:46:09 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:46:09 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 05:46:09 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:46:09 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 05:46:09 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 05:46:09 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 05:46:09 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:46:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:09 INFO - https://queue.taskcluster.net/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:46:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:46:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:46:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/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 05:46:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/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 05:46:10 INFO - Downloaded 18788470 bytes. 05:46:10 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'] 05:46:10 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 05:46:13 INFO - Return code: 0 05:46:13 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 05:46: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')]} 05:46:13 INFO - https://queue.taskcluster.net/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:46:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:46:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 05:46:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/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 05:46:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/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 05:46:34 INFO - Downloaded 18788470 bytes. 05:46:34 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'] 05:46:34 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 05:46:38 INFO - Return code: 0 05:46:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:38 INFO - https://queue.taskcluster.net/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:46:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:46:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 05:46:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/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 05:46:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/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 05:46:39 INFO - Downloaded 14405242 bytes. 05:46:39 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'] 05:46:39 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 05:46:39 INFO - Return code: 0 05:46: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')]} 05:46:39 INFO - https://queue.taskcluster.net/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:46:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:46:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:46:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 05:46:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Dyy_7w0eSXKvQm6HCnarNA/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 05:46:45 INFO - Downloaded 112408046 bytes. 05:46:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:46:45 INFO - mkdir: /builds/slave/test/properties 05:46:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:46:45 INFO - Writing to file /builds/slave/test/properties/build_url 05:46:45 INFO - Contents: 05:46:45 INFO - build_url:https://queue.taskcluster.net/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 05:46:46 INFO - Running post-action listener: _resource_record_post_action 05:46:46 INFO - Running post-action listener: set_extra_try_arguments 05:46:46 INFO - ##### 05:46:46 INFO - ##### Finished download-and-extract step (success) 05:46:46 INFO - ##### 05:46:46 INFO - ##### 05:46:46 INFO - ##### Running populate-webroot step. 05:46:46 INFO - ##### 05:46:46 INFO - Running pre-action listener: _resource_record_pre_action 05:46:46 INFO - Running main action method: populate_webroot 05:46:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:46:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:46:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:46:46 INFO - '--tppagecycles', 05:46:46 INFO - '7'], 05:46:46 INFO - 'tests': ['tsvgm']}}, 05:46:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:46:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:46:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:46:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:46:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:46:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:46:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:46 INFO - 'talos_options': ['--disable-e10s'], 05:46:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:46:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:46:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:46 INFO - 'talos_options': ['--disable-e10s'], 05:46:46 INFO - 'tests': ['damp', 'tps']}, 05:46:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:46 INFO - 'tests': ['damp', 'tps']}, 05:46:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:46:46 INFO - 'tests': ['dromaeo_dom']}, 05:46:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:46:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:46:46 INFO - 'tests': ['basic_compositor_video']}, 05:46:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:46:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:46:46 INFO - 'tests': ['a11yr', 05:46:46 INFO - 'ts_paint', 05:46:46 INFO - 'tpaint', 05:46:46 INFO - 'sessionrestore', 05:46:46 INFO - 'sessionrestore_no_auto_restore', 05:46:46 INFO - 'tabpaint']}, 05:46:46 INFO - 'other-e10s': {'tests': ['a11yr', 05:46:46 INFO - 'ts_paint', 05:46:46 INFO - 'tpaint', 05:46:46 INFO - 'sessionrestore', 05:46:46 INFO - 'sessionrestore_no_auto_restore', 05:46:46 INFO - 'tabpaint']}, 05:46:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:46:46 INFO - 'ts_paint', 05:46:46 INFO - 'tpaint', 05:46:46 INFO - 'sessionrestore', 05:46:46 INFO - 'sessionrestore_no_auto_restore', 05:46:46 INFO - 'tabpaint']}, 05:46:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:46:46 INFO - 'ts_paint', 05:46:46 INFO - 'tpaint', 05:46:46 INFO - 'sessionrestore', 05:46:46 INFO - 'sessionrestore_no_auto_restore', 05:46:46 INFO - 'tabpaint']}, 05:46:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:46:46 INFO - 'tests': ['a11yr', 05:46:46 INFO - 'ts_paint', 05:46:46 INFO - 'tpaint', 05:46:46 INFO - 'sessionrestore', 05:46:46 INFO - 'sessionrestore_no_auto_restore', 05:46:46 INFO - 'tabpaint']}, 05:46:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:46:46 INFO - 'tests': ['a11yr', 05:46:46 INFO - 'ts_paint', 05:46:46 INFO - 'tpaint', 05:46:46 INFO - 'sessionrestore', 05:46:46 INFO - 'sessionrestore_no_auto_restore', 05:46:46 INFO - 'tabpaint']}, 05:46:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:46:46 INFO - 'tests': ['tsvgx', 05:46:46 INFO - 'tsvgr_opacity', 05:46:46 INFO - 'tart', 05:46:46 INFO - 'tscrollx', 05:46:46 INFO - 'cart']}, 05:46:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:46:46 INFO - 'tsvgr_opacity', 05:46:46 INFO - 'tart', 05:46:46 INFO - 'tscrollx', 05:46:46 INFO - 'cart']}, 05:46:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:46 INFO - 'talos_options': ['--disable-e10s'], 05:46:46 INFO - 'tests': ['tp5o']}, 05:46:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:46 INFO - 'tests': ['tp5o']}, 05:46:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:46 INFO - 'talos_options': ['--disable-e10s', 05:46:46 INFO - '--xperf_path', 05:46:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:46:46 INFO - 'tests': ['tp5n']}, 05:46:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:46:46 INFO - 'talos_options': ['--xperf_path', 05:46:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:46:46 INFO - 'tests': ['tp5n']}}, 05:46:46 INFO - 'talos.zip': {'path': '', 05:46:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:46:46 INFO - Running post-action listener: _resource_record_post_action 05:46:46 INFO - ##### 05:46:46 INFO - ##### Finished populate-webroot step (success) 05:46:46 INFO - ##### 05:46:46 INFO - ##### 05:46:46 INFO - ##### Running create-virtualenv step. 05:46:46 INFO - ##### 05:46:46 INFO - Running pre-action listener: _resource_record_pre_action 05:46:46 INFO - Running main action method: create_virtualenv 05:46:46 INFO - Creating virtualenv /builds/slave/test/build/venv 05:46:46 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 05:46:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:46:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:46:47 INFO - Using real prefix '/tools/python27' 05:46:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:46:47 INFO - Installing distribute.............................................................................................................................................................................................done. 05:46:50 INFO - Installing pip.................done. 05:46:50 INFO - Return code: 0 05:46:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:50 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 0x10bbf6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc691c88950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb5ad30>, '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 0x10b8bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb52b20>, '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.ITFhZSXBLk/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.pgryuwUo6F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:46:50 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 05:46:50 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 05:46:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITFhZSXBLk/Render', 05:46:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:50 INFO - 'HOME': '/Users/cltbld', 05:46:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:50 INFO - 'LOGNAME': 'cltbld', 05:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:50 INFO - 'MOZ_NO_REMOTE': '1', 05:46:50 INFO - 'NO_EM_RESTART': '1', 05:46:50 INFO - 'PAGER': '/bin/cat', 05:46:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:50 INFO - 'PWD': '/builds/slave/test', 05:46:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:50 INFO - 'SHELL': '/bin/bash', 05:46:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pgryuwUo6F/Listeners', 05:46:50 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:46:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:50 INFO - 'USER': 'cltbld', 05:46:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:50 INFO - 'XPC_FLAGS': '0x0', 05:46:50 INFO - 'XPC_SERVICE_NAME': '0', 05:46:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:50 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 05:46:50 INFO - Cleaning up... 05:46:50 INFO - Return code: 0 05:46:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:50 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 0x10bbf6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc691c88950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb5ad30>, '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 0x10b8bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb52b20>, '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.ITFhZSXBLk/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.pgryuwUo6F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:46:50 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 05:46:50 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 05:46:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITFhZSXBLk/Render', 05:46:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:50 INFO - 'HOME': '/Users/cltbld', 05:46:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:50 INFO - 'LOGNAME': 'cltbld', 05:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:50 INFO - 'MOZ_NO_REMOTE': '1', 05:46:50 INFO - 'NO_EM_RESTART': '1', 05:46:50 INFO - 'PAGER': '/bin/cat', 05:46:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:50 INFO - 'PWD': '/builds/slave/test', 05:46:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:50 INFO - 'SHELL': '/bin/bash', 05:46:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pgryuwUo6F/Listeners', 05:46:50 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:46:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:50 INFO - 'USER': 'cltbld', 05:46:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:50 INFO - 'XPC_FLAGS': '0x0', 05:46:50 INFO - 'XPC_SERVICE_NAME': '0', 05:46:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:51 INFO - Downloading/unpacking psutil>=3.1.1 05:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:46:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:46:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:46:54 INFO - Installing collected packages: psutil 05:46:54 INFO - Running setup.py install for psutil 05:46:54 INFO - building 'psutil._psutil_osx' extension 05:46:54 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 05:46:54 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 05:46:54 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 05:46:54 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 05:46:54 INFO - building 'psutil._psutil_posix' extension 05:46:54 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 05:46:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:46:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:46:54 INFO - ^ 05:46:55 INFO - 1 warning generated. 05:46:55 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 05:46:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:46:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:46:55 INFO - Successfully installed psutil 05:46:55 INFO - Cleaning up... 05:46:55 INFO - Return code: 0 05:46:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:46:55 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')]} 05:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:55 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')]} 05:46:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:55 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 0x10bbf6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc691c88950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb5ad30>, '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 0x10b8bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb52b20>, '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.ITFhZSXBLk/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.pgryuwUo6F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:46:55 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 05:46:55 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 05:46:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITFhZSXBLk/Render', 05:46:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:55 INFO - 'HOME': '/Users/cltbld', 05:46:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:55 INFO - 'LOGNAME': 'cltbld', 05:46:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:55 INFO - 'MOZ_NO_REMOTE': '1', 05:46:55 INFO - 'NO_EM_RESTART': '1', 05:46:55 INFO - 'PAGER': '/bin/cat', 05:46:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:55 INFO - 'PWD': '/builds/slave/test', 05:46:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:55 INFO - 'SHELL': '/bin/bash', 05:46:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pgryuwUo6F/Listeners', 05:46:55 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:46:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:55 INFO - 'USER': 'cltbld', 05:46:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:55 INFO - 'XPC_FLAGS': '0x0', 05:46:55 INFO - 'XPC_SERVICE_NAME': '0', 05:46:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:46:55 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 05:46:55 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 05:46:55 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 05:46:55 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 05:46:55 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 05:46:55 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 05:46:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:46:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:46:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:46:58 INFO - Installing collected packages: mozsystemmonitor 05:46:58 INFO - Running setup.py install for mozsystemmonitor 05:46:58 INFO - Successfully installed mozsystemmonitor 05:46:58 INFO - Cleaning up... 05:46:58 INFO - Return code: 0 05:46:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:46:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:46:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:46:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:46:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bbf6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc691c88950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb5ad30>, '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 0x10b8bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb52b20>, '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.ITFhZSXBLk/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.pgryuwUo6F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:46:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:46:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITFhZSXBLk/Render', 05:46:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:46:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:46:58 INFO - 'HOME': '/Users/cltbld', 05:46:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:46:58 INFO - 'LOGNAME': 'cltbld', 05:46:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:46:58 INFO - 'MOZ_NO_REMOTE': '1', 05:46:58 INFO - 'NO_EM_RESTART': '1', 05:46:58 INFO - 'PAGER': '/bin/cat', 05:46:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:46:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:46:58 INFO - 'PWD': '/builds/slave/test', 05:46:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:46:58 INFO - 'SHELL': '/bin/bash', 05:46:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pgryuwUo6F/Listeners', 05:46:58 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:46:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:46:58 INFO - 'USER': 'cltbld', 05:46:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:46:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:46:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:46:58 INFO - 'XPC_FLAGS': '0x0', 05:46:58 INFO - 'XPC_SERVICE_NAME': '0', 05:46:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:46:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:46:58 INFO - Downloading/unpacking jsonschema==2.5.1 05:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:46:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:47:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:47:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:47:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:47:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:47:01 INFO - Downloading functools32-3.2.3-2.tar.gz 05:47:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:47:01 INFO - warning: no files found matching '*.txt' 05:47:01 INFO - no previously-included directories found matching 'build' 05:47:01 INFO - no previously-included directories found matching 'dist' 05:47:01 INFO - no previously-included directories found matching '.git*' 05:47:01 INFO - Installing collected packages: jsonschema, functools32 05:47:01 INFO - Running setup.py install for functools32 05:47:01 INFO - warning: no files found matching '*.txt' 05:47:01 INFO - no previously-included directories found matching 'build' 05:47:01 INFO - no previously-included directories found matching 'dist' 05:47:01 INFO - no previously-included directories found matching '.git*' 05:47:01 INFO - Successfully installed jsonschema functools32 05:47:01 INFO - Cleaning up... 05:47:01 INFO - Return code: 0 05:47:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:47:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:47:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:47:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:01 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 0x10bbf6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc691c88950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb5ad30>, '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 0x10b8bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb52b20>, '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.ITFhZSXBLk/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.pgryuwUo6F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:01 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 05:47:01 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 05:47:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITFhZSXBLk/Render', 05:47:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:01 INFO - 'HOME': '/Users/cltbld', 05:47:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:01 INFO - 'LOGNAME': 'cltbld', 05:47:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:01 INFO - 'MOZ_NO_REMOTE': '1', 05:47:01 INFO - 'NO_EM_RESTART': '1', 05:47:01 INFO - 'PAGER': '/bin/cat', 05:47:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:01 INFO - 'PWD': '/builds/slave/test', 05:47:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:01 INFO - 'SHELL': '/bin/bash', 05:47:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pgryuwUo6F/Listeners', 05:47:01 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:47:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:01 INFO - 'USER': 'cltbld', 05:47:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:01 INFO - 'XPC_FLAGS': '0x0', 05:47:01 INFO - 'XPC_SERVICE_NAME': '0', 05:47:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:01 INFO - Downloading/unpacking blobuploader==1.2.4 05:47:01 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 05:47:01 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 05:47:01 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 05:47:01 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 05:47:01 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 05:47:01 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 05:47:04 INFO - Downloading blobuploader-1.2.4.tar.gz 05:47:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:47:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:47:04 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 05:47:04 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 05:47:04 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 05:47:04 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 05:47:04 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 05:47:04 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 05:47:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:47:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:47:05 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 05:47:05 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 05:47:05 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 05:47:05 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 05:47:05 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 05:47:05 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 05:47:05 INFO - Downloading docopt-0.6.1.tar.gz 05:47:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:47:05 INFO - Installing collected packages: blobuploader, requests, docopt 05:47:05 INFO - Running setup.py install for blobuploader 05:47:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:47:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:47:05 INFO - Running setup.py install for requests 05:47:06 INFO - Running setup.py install for docopt 05:47:06 INFO - Successfully installed blobuploader requests docopt 05:47:06 INFO - Cleaning up... 05:47:06 INFO - Return code: 0 05:47:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:47:06 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')]} 05:47:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:06 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')]} 05:47:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:06 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 0x10bbf6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc691c88950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb5ad30>, '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 0x10b8bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb52b20>, '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.ITFhZSXBLk/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.pgryuwUo6F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:06 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 05:47:06 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 05:47:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITFhZSXBLk/Render', 05:47:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:06 INFO - 'HOME': '/Users/cltbld', 05:47:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:06 INFO - 'LOGNAME': 'cltbld', 05:47:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:06 INFO - 'MOZ_NO_REMOTE': '1', 05:47:06 INFO - 'NO_EM_RESTART': '1', 05:47:06 INFO - 'PAGER': '/bin/cat', 05:47:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:06 INFO - 'PWD': '/builds/slave/test', 05:47:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:06 INFO - 'SHELL': '/bin/bash', 05:47:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pgryuwUo6F/Listeners', 05:47:06 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:47:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:06 INFO - 'USER': 'cltbld', 05:47:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:06 INFO - 'XPC_FLAGS': '0x0', 05:47:06 INFO - 'XPC_SERVICE_NAME': '0', 05:47:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:47:06 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-lEv_6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:47:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:47:06 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-eonIAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:47:07 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-UlIkaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:47:07 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-pd7vfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:47:07 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-KqdvXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:47:07 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-HuShbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:47:07 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-V74dvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:47:07 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-hZtNpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:47:07 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-Fn12Cq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:47:07 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-zzvYqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:47:07 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-8ZV516-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:47:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:47:07 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-zsHkzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:47:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:47:08 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-zbFuex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:47:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:47:08 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-onHULt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:47:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:47:08 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-k0fUtQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:47:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:47:08 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-wtzRvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:47:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:47:08 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-YceuqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:47:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:47:08 INFO - Running setup.py install for manifestparser 05:47:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:47:08 INFO - Running setup.py install for mozcrash 05:47:08 INFO - Running setup.py install for mozdebug 05:47:08 INFO - Running setup.py install for mozdevice 05:47:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:47:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:47:09 INFO - Running setup.py install for mozfile 05:47:09 INFO - Running setup.py install for mozhttpd 05:47:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:47:09 INFO - Running setup.py install for mozinfo 05:47:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:47:09 INFO - Running setup.py install for mozInstall 05:47:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:47:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:47:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:47:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:47:09 INFO - Running setup.py install for mozleak 05:47:09 INFO - Running setup.py install for mozlog 05:47:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:47:10 INFO - Running setup.py install for moznetwork 05:47:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:47:10 INFO - Running setup.py install for mozprocess 05:47:10 INFO - Running setup.py install for mozprofile 05:47:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:47:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:47:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:47:10 INFO - Running setup.py install for mozrunner 05:47:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:47:10 INFO - Running setup.py install for mozscreenshot 05:47:10 INFO - Running setup.py install for moztest 05:47:10 INFO - Running setup.py install for mozversion 05:47:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:47:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:47:11 INFO - Cleaning up... 05:47:11 INFO - Return code: 0 05:47:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:47:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:47:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:47:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:11 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 0x10bbf6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc691c88950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb5ad30>, '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 0x10b8bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb52b20>, '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.ITFhZSXBLk/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.pgryuwUo6F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:11 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 05:47:11 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 05:47:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITFhZSXBLk/Render', 05:47:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:11 INFO - 'HOME': '/Users/cltbld', 05:47:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:11 INFO - 'LOGNAME': 'cltbld', 05:47:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:11 INFO - 'MOZ_NO_REMOTE': '1', 05:47:11 INFO - 'NO_EM_RESTART': '1', 05:47:11 INFO - 'PAGER': '/bin/cat', 05:47:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:11 INFO - 'PWD': '/builds/slave/test', 05:47:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:11 INFO - 'SHELL': '/bin/bash', 05:47:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pgryuwUo6F/Listeners', 05:47:11 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:47:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:11 INFO - 'USER': 'cltbld', 05:47:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:11 INFO - 'XPC_FLAGS': '0x0', 05:47:11 INFO - 'XPC_SERVICE_NAME': '0', 05:47:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:47:11 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-N29D4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:47:11 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)) 05:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:47:11 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-n_9aCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:47:11 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)) 05:47:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:47:11 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-LMczKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:47:12 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)) 05:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:47:12 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-DZgX_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:47:12 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)) 05:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:47:12 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-cbNA4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:47:12 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)) 05:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:47:12 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-MyX95e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:47:12 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)) 05:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:47:12 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-Ov_0Gf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:47:12 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)) 05:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:47:12 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-xypsRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:47:12 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)) 05:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:47:12 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-MGsOhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:47:12 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)) 05:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:47:12 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-ltVCed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:47:12 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)) 05:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:47:12 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-uTkpLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:47:12 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)) 05:47:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:47:12 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-JgOagE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:47:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:47:13 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-hyuVWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:47:13 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)) 05:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:47:13 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-y0quXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:47:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:47:13 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-QWl0P_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:47:13 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)) 05:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:47:13 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-s21KJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:47:13 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)) 05:47:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:47:13 INFO - Running setup.py (path:/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/pip-cOwDwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:47:13 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)) 05:47:13 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)) 05:47:13 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)) 05:47:13 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)) 05:47:13 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)) 05:47:13 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)) 05:47:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:47: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 05:47: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 05:47: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 05:47: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 05:47: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 05:47: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 05:47:16 INFO - Downloading blessings-1.6.tar.gz 05:47:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:47:16 INFO - Installing collected packages: blessings 05:47:16 INFO - Running setup.py install for blessings 05:47:16 INFO - Successfully installed blessings 05:47:16 INFO - Cleaning up... 05:47:16 INFO - Return code: 0 05:47:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:47:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:47:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:47:16 INFO - Reading from file tmpfile_stdout 05:47:16 INFO - Current package versions: 05:47:16 INFO - blessings == 1.6 05:47:16 INFO - blobuploader == 1.2.4 05:47:16 INFO - docopt == 0.6.1 05:47:16 INFO - functools32 == 3.2.3-2 05:47:16 INFO - jsonschema == 2.5.1 05:47:16 INFO - manifestparser == 1.1 05:47:16 INFO - mozInstall == 1.12 05:47:16 INFO - mozcrash == 0.17 05:47:16 INFO - mozdebug == 0.1 05:47:16 INFO - mozdevice == 0.48 05:47:16 INFO - mozfile == 1.2 05:47:16 INFO - mozhttpd == 0.7 05:47:16 INFO - mozinfo == 0.9 05:47:16 INFO - mozleak == 0.1 05:47:16 INFO - mozlog == 3.2 05:47:16 INFO - moznetwork == 0.27 05:47:16 INFO - mozprocess == 0.22 05:47:16 INFO - mozprofile == 0.28 05:47:16 INFO - mozrunner == 6.11 05:47:16 INFO - mozscreenshot == 0.1 05:47:16 INFO - mozsystemmonitor == 0.3 05:47:16 INFO - moztest == 0.7 05:47:16 INFO - mozversion == 1.4 05:47:16 INFO - psutil == 3.1.1 05:47:16 INFO - requests == 1.2.3 05:47:16 INFO - wsgiref == 0.1.2 05:47:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:47:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47:16 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 0x10bbf6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc691c88950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb5ad30>, '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 0x10b8bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb52b20>, '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.ITFhZSXBLk/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.pgryuwUo6F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47:16 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 05:47:16 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 05:47:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITFhZSXBLk/Render', 05:47:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:16 INFO - 'HOME': '/Users/cltbld', 05:47:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:16 INFO - 'LOGNAME': 'cltbld', 05:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:16 INFO - 'MOZ_NO_REMOTE': '1', 05:47:16 INFO - 'NO_EM_RESTART': '1', 05:47:16 INFO - 'PAGER': '/bin/cat', 05:47:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:16 INFO - 'PWD': '/builds/slave/test', 05:47:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:16 INFO - 'SHELL': '/bin/bash', 05:47:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pgryuwUo6F/Listeners', 05:47:16 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:47:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:16 INFO - 'USER': 'cltbld', 05:47:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:16 INFO - 'XPC_FLAGS': '0x0', 05:47:16 INFO - 'XPC_SERVICE_NAME': '0', 05:47:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:17 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)) 05:47:17 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)) 05:47:17 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)) 05:47:17 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)) 05:47:17 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)) 05:47:17 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)) 05:47:17 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)) 05:47:17 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)) 05:47:17 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)) 05:47:17 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)) 05:47:17 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)) 05:47:17 INFO - Cleaning up... 05:47:17 INFO - Return code: 0 05:47:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:47: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')]} 05:47:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:47:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47: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')]} 05:47:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:47:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:47: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bbf6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc691c88950>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bb5ad30>, '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 0x10b8bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb52b20>, '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.ITFhZSXBLk/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.pgryuwUo6F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:47: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', 'jsonschema'] in /builds/slave/test/build 05:47: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 jsonschema 05:47:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITFhZSXBLk/Render', 05:47:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:17 INFO - 'HOME': '/Users/cltbld', 05:47:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:17 INFO - 'LOGNAME': 'cltbld', 05:47:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:17 INFO - 'MOZ_NO_REMOTE': '1', 05:47:17 INFO - 'NO_EM_RESTART': '1', 05:47:17 INFO - 'PAGER': '/bin/cat', 05:47:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:17 INFO - 'PWD': '/builds/slave/test', 05:47:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:17 INFO - 'SHELL': '/bin/bash', 05:47:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pgryuwUo6F/Listeners', 05:47:17 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:47:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:17 INFO - 'USER': 'cltbld', 05:47:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:17 INFO - 'XPC_FLAGS': '0x0', 05:47:17 INFO - 'XPC_SERVICE_NAME': '0', 05:47:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:47:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:47:17 INFO - Cleaning up... 05:47:17 INFO - Return code: 0 05:47:17 INFO - Running post-action listener: _resource_record_post_action 05:47:17 INFO - Running post-action listener: _start_resource_monitoring 05:47:17 INFO - Starting resource monitoring. 05:47:17 INFO - ##### 05:47:17 INFO - ##### Finished create-virtualenv step (success) 05:47:17 INFO - ##### 05:47:17 INFO - ##### 05:47:17 INFO - ##### Running install step. 05:47:17 INFO - ##### 05:47:17 INFO - Running pre-action listener: _resource_record_pre_action 05:47:17 INFO - Running main action method: install 05:47:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:47:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:47:17 INFO - Reading from file tmpfile_stdout 05:47:17 INFO - Detecting whether we're running mozinstall >=1.0... 05:47:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:47:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:47:17 INFO - Reading from file tmpfile_stdout 05:47:17 INFO - Output received: 05:47:17 INFO - Usage: mozinstall [options] installer 05:47:17 INFO - Options: 05:47:17 INFO - -h, --help show this help message and exit 05:47:17 INFO - -d DEST, --destination=DEST 05:47:17 INFO - Directory to install application into. [default: 05:47:17 INFO - "/builds/slave/test"] 05:47:17 INFO - --app=APP Application being installed. [default: firefox] 05:47:17 INFO - mkdir: /builds/slave/test/build/application 05:47:17 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'] 05:47:17 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 05:47:52 INFO - Reading from file tmpfile_stdout 05:47:52 INFO - Output received: 05:47:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:47:52 INFO - Running post-action listener: _resource_record_post_action 05:47:52 INFO - ##### 05:47:52 INFO - ##### Finished install step (success) 05:47:52 INFO - ##### 05:47:52 INFO - ##### 05:47:52 INFO - ##### Running run-tests step. 05:47:52 INFO - ##### 05:47:52 INFO - Running pre-action listener: _resource_record_pre_action 05:47:52 INFO - Running main action method: run_tests 05:47:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:47:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:47:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:47:52 INFO - 2016-06-30 05:47:52.494 screenresolution[1642:8722] starting screenresolution argv=screenresolution get 05:47:52 INFO - 2016-06-30 05:47:52.497 screenresolution[1642:8722] Display 0: 1600x1200x32@60 05:47:52 INFO - 2016-06-30 05:47:52.503 screenresolution[1643:8725] starting screenresolution argv=screenresolution list 05:47:52 INFO - Available Modes on Display 0 05:47:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:47:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:47:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:47:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:47:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:47:52 INFO - Intel Iris: 05:47:52 INFO - Chipset Model: Intel Iris 05:47:52 INFO - Type: GPU 05:47:52 INFO - Bus: Built-In 05:47:52 INFO - VRAM (Dynamic, Max): 1536 MB 05:47:52 INFO - Vendor: Intel (0x8086) 05:47:52 INFO - Device ID: 0x0a2e 05:47:52 INFO - Revision ID: 0x0009 05:47:52 INFO - Displays: 05:47:52 INFO - Display: 05:47:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:47:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:47:52 INFO - Display Serial Number: 1600x1200 60 05:47:52 INFO - Main Display: Yes 05:47:52 INFO - Mirror: Off 05:47:52 INFO - Online: Yes 05:47:52 INFO - Rotation: Supported 05:47:52 INFO - Return code: 0 05:47:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:47:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:47:52 INFO - Python 2.7.3 05:47:52 INFO - Return code: 0 05:47:52 INFO - grabbing minidump binary from tooltool 05:47:52 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')]} 05:47:52 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 0x10bb5ad30>, '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 0x10b8bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb52b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:47:52 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 05:47:52 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 05:47:52 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 05:47:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:47:52 INFO - Return code: 0 05:47:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:47:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:47:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:47:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:47:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:47:52 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/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:47:52 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/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0226 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:47:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITFhZSXBLk/Render', 05:47:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:47:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:47:52 INFO - 'HOME': '/Users/cltbld', 05:47:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:47:52 INFO - 'LOGNAME': 'cltbld', 05:47:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:47:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:47:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:47:52 INFO - 'MOZ_NO_REMOTE': '1', 05:47:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:47:52 INFO - 'NO_EM_RESTART': '1', 05:47:52 INFO - 'PAGER': '/bin/cat', 05:47:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:47:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:47:52 INFO - 'PWD': '/builds/slave/test', 05:47:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:47:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:47:52 INFO - 'SHELL': '/bin/bash', 05:47:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pgryuwUo6F/Listeners', 05:47:52 INFO - 'TMPDIR': '/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/', 05:47:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:47:52 INFO - 'USER': 'cltbld', 05:47:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:47:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:47:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:47:52 INFO - 'XPC_FLAGS': '0x0', 05:47:52 INFO - 'XPC_SERVICE_NAME': '0', 05:47:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:47:52 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/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0226', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:47:53 INFO - mozversion application_buildid: 20160630035327 05:47:53 INFO - mozversion application_changeset: 7b4e8a8e4f0b3a13e0862d8b54f7e9967069b6d9 05:47:53 INFO - mozversion application_display_name: Nightly 05:47:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:47:53 INFO - mozversion application_name: Firefox 05:47:53 INFO - mozversion application_remotingname: firefox 05:47:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:47:53 INFO - mozversion application_vendor: Mozilla 05:47:53 INFO - mozversion application_version: 50.0a1 05:47:53 INFO - mozversion platform_buildid: 20160630035327 05:47:53 INFO - mozversion platform_changeset: 7b4e8a8e4f0b3a13e0862d8b54f7e9967069b6d9 05:47:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:47:53 INFO - mozversion platform_version: 50.0a1 05:47:53 INFO - using testdate: 1467290873 05:47:53 INFO - actual date: 1467290873 05:47:53 INFO - starting webserver on 'localhost:49272' 05:47:53 INFO - SUITE-START | Running 2 tests 05:47:53 INFO - TEST-START | dromaeo_css 05:47:53 INFO - operating with platform_type : mac_ 05:47:53 INFO - Initialising browser for dromaeo_css test... 05:47:53 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpetJ4x7/profile http://localhost:49272/getInfo.html) 05:47:55 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 05:47:55 INFO - PROCESS | 1653 | colorDepth:24 05:47:55 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 05:47:55 INFO - PROCESS | 1653 | __metrics 05:47:55 INFO - PROCESS | 1653 | 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" 05:47:55 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:47:56 INFO - TEST-INFO | 1653: exit 0 05:47:56 INFO - Browser initialized. 05:47:56 INFO - Running cycle 1/1 for dromaeo_css test... 05:47:56 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmpetJ4x7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:48:08 INFO - PROCESS | 1656 | RSS: Main: 243716096 05:48:08 INFO - PROCESS | 1656 | 05:48:59 INFO - PROCESS | 1656 | 1467290939377 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] 05:51:09 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 05:51:11 INFO - PROCESS | 1656 | RSS: Main: 398802944 05:51:11 INFO - PROCESS | 1656 | 05:54:14 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 05:54:14 INFO - PROCESS | 1656 | RSS: Main: 381763584 05:54:14 INFO - PROCESS | 1656 | 05:57:17 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 05:57:17 INFO - PROCESS | 1656 | RSS: Main: 372314112 05:57:17 INFO - PROCESS | 1656 | 06:00:19 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 06:00:20 INFO - PROCESS | 1656 | RSS: Main: 372207616 06:00:20 INFO - PROCESS | 1656 | 06:03:23 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 06:03:24 INFO - PROCESS | 1656 | RSS: Main: 339230720 06:03:24 INFO - PROCESS | 1656 | 06:06:27 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 06:06:27 INFO - PROCESS | 1656 | RSS: Main: 415944704 06:06:27 INFO - PROCESS | 1656 | 06:06:27 INFO - PROCESS | 1656 | __start_tp_report 06:06:27 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 06:06:27 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 06:06:27 INFO - PROCESS | 1656 | |i|pagename|runs| 06:06:27 INFO - PROCESS | 1656 | |0;dojo.html;2801.369691819341;3310.5034244863273;3347.113015051011;3374.881879134231;3382.2559037011847;4472.485664148624;5528;5544.972275138623;5575.498205161534;5629.549636029124;5563.998480273549;5592.972035139818;5597.63615365001;5634.690092044946;5672.829815105543;5051.1413059779925;5108.208227724708;5154.793808247666;5166.225066240057;5302.125149350352;5200.063988482072;5210.921836172461;5222.399424066224;5234.136367499358;5240.502152295545;5067.797288108472;5079.314292570492;5080.314157588734;5102.770375333119;5119.206522988943;5093.5415812576675;5106;5159.51869788804;5179.041877252683;5189.870253243661;5092.032513822362;5127.5385215330425;5130.486951304877;5176.275321454999;5196.350456192976;5469;5544.6118771686;5550.250716153308;5602.13166959122;5611.130274807411;6139.017757158877;6154.292256390528;6169.629822210637;6187.907181392283;6195.845103872394;6058.030715085565;6175.258968923713;6226.750929962796;6258.968705156445;6265.530085243579;5635.154726790973;5698.287714035746;5718.170865224519;5722.170285308607;5780.248567686258;6274.435300822948;6304.275008374096;6306.148669929593;6315.96842015787;6317;5877.441643043904;5954.345022047572;6017.548683848729;6055.394460553909;6055.878882422327;6147.538934579924;6254.437100660963;6262.6868656566985;6264;6264.968675156595;6113.388661133851;6115.1744514490865;6161.383861613893;6174.5060395168275;6180.3201647818705;4438.467383913887;5651.57613179005;6024.096385542204;6100.847478813065;6195.628262304276;4925.482824303468;4942.8764280893265;4950.183219768698;4953.975230123826;4989.276554899519;4400.977995110068;4404.339349097597;4412.867613971586;4414.735115892992;4433.046894917608;5482.972585137129;5516.282883225235;5520.254765606592;5597.692126932976;5600;5097.439281679012;6108.615274519878;6134.4978165938865;6162.599431037058;6168.0439531872635;5520.641158324696;5586.357568879631;5587.664740115606;5587.860303492445;5594.804181853616;4581.862544123682;5379.919301210485;5390.757415916215;5394.91907621386;5407.5944304176555;6279.905801413074;6300.306966233802;6300.338464461165;6325;6334.619922804646;6023.728932197974;6077.4226448487325;6124.74712746768;6235.688215589294;6240.53196010292;6251.593646412968;6321.462675672619;6326.177596912556;6333.018382150774;6333.334999825136;6134.478569321657;6136.171616831671;6139.508839292756;6144.293406258337;6168.907466387918;6059.6364218147055;6112.205413296243;6230.127782110599;6239.594426362272;6253.6560489173535;5927.496162826035;5953;5991.790287340096;6014.789482368096;6023.939760602338;5927.585069045125;5945.762169513344;5948.583599148018;5954.017587098254;5988.101784732325;5657.717114144359;5677.261955945701;5761.683107428964;5777.8555536111935;5783.132530120515;5831.912521312269;5893.528517718658;5921.437463440966;5948.702564871825;6001.639901605918;5850.063989761617;5876.911846322223;5886.411358864079;5899.203607512931;5962.970185149046 06:06:27 INFO - PROCESS | 1656 | |1;ext.html;523.879507713226;526.9552088072513;534.7486681259809;549.4313385645858;575.5568212476393;30408.69591304089;41394.37908431376;41654.583454165484;42061.789691051505;44897.775511122396;43669.126617467635;43869.7806510967;43902.560974390246;43905.34141987873;43989.34015989755;8728.694495692653;9623.133917947365;9820.950895245549;9821.558029888636;9824.705258842247;8732.607032683545;8914.465132092062;8923.152300531472;8925.196732294093;11727.648170554898;11701.122415818805;11984.460699268553;12021.639350819445;12029.157958942878;12064.879351206508;11860.822087668692;11906.28562286257;11924.10569207313;11925.701857453632;11944.343061131634;8708.21626053652;11474.311541307548;11646.941765291203;11781.882181178249;11852.11109166816;41287.793561032006;41459.37810932838;41486.585134148874;41519.1696166078;41758;2889.5376739721755;3594.0475773919934;3916.0014196380025;4123.711340206187;4147.274227010261;31304;32567.511487327472;32893.50659740106;32914.51884665172;33008.17479563006;3254.576905002358;3325.401427743005;3366.2425954160362;3411.778234414755;3455.550778398792;477034;480022;482354.17645823443;483278;485602;60424.093638595456;60656;60716.69641651763;60833.69583152144;60919;52820;64175.679121604095;64268.67865660642;64337.67831160814;64468.67765661235;16553.751693724604;16812.907161034494;16836.32654693823;16843.494695159166;16885;13021.739565208643;13027.869721302666;13069.477220911058;13077.738445231042;13093.083484156208;15109.244537773067;15583.376664933276;16916.91541542309;17527.73708394375;17550.912245438947;7257.782266532013;7341.586162434493;7364.3372096511575;7413.221611730798;7487.737929172453;909.4043401571981;929.6002718830968;942.006183476439;1013.2400699475394;1093.6992327110108;30127.849360753054;30196;31142;31398.215044623612;31455.52816707751;1024.5850430575629;1101.1851230089835;1135.8750537440874;1152.9942350288195;1160.2864238493266;15760.92119539418;15778.92110539463;15874.841251587337;16114.516564503083;16210.35158593643;545455.2727236418;550277;550885;552346;553648;59902.70048649729;59917.40082599205;59936.700316498136;60244.397556024756;60245.69877150674;1350.5543170753629;1389.215093472185;1415.1509094543603;1415.4338264694;1416.1007760072223;30135.547966780956;30349.089527314216;30352.84823575868;30854.691453085183;31763;50978.23532647087;51234.74382628063;51375.743121284155;51391.74304128456;51417;465012;466572;466613;466669;466874;35353.469697955064;41930.580694192664;42340.36489452722;42710.57289427066;42812.357814633426;48058.27912581244;48320.5167948316;48581.27128093011;49296.50703492919;49450;33404.66595334113;38847;40633.577824777174;41068;41155;30731.385372292876;31016.0695179145;39913;40128.79613611596;41106.79446602748;38727.22545549017;38868.833934981994;38889.02777430587;39123;39159.02102447462;33996.3200735979;34444.82777586096;35576.46635300524;35582.822085889406;35746.64253357433;25803.612945806202;25859.482810344063;25953.740462595888;26021.73978260269;26324.21027369182 06:06:27 INFO - PROCESS | 1656 | |2;jquery.html;354.8137227955323;359.54337990751765;387.71309231168937;390.20008981587756;408.97750623715683;15739.449119280827;15803.367865285389;20537.589248215027;20581.27965521207;20789.16843326269;17489.300427982897;19308.71036934447;20137.798622013775;20952.68570971433;20987.055582498786;5005.524475174852;5349.7592608332625;5517.200005999128;5518.061929471999;6078.908816367758;5979.820605381846;6013.098035294719;6036.667983260919;6037.788677396292;6050.576459647827;5830.766769329243;5916.615419997708;5923.940760592403;5966.343702192756;5968.582199246054;5860.970695146519;5903.586748927577;6000.429959153874;6018;6024.638521688713;4993.101241776479;4996.625253105995;5000.124978128849;5003.699778013331;5042.546170844642;13193.670158246025;13710.588682339545;13711.657208569766;23219;24469.63295550568;2495.6131799571094;2512.924612261617;2599.272203782925;2624.128648976772;2738.561830107173;10098.394096354243;10162.237832162617;10312.79374412515;10455.947720261425;15850.841491585166;1809.1496996411797;1837.6875931091747;1889.8677092603523;1890.8487321014422;1943.7570303712037;109829.45085274523;111619;122016.16975745371;124039.75960240372;124813;11189.762047590351;11438.771224575628;11570.537178512765;11592.594259201056;11800.52797888092;11757.882421175678;14517.491887783877;14520.201388923711;14545.909056820781;14590.416383344547;8368.748937531884;8553.187447192508;8555.743327700178;8661.523616201044;8690.652373904974;6905.620190889549;6959.338862808025;8562.143785621513;8641.438306510057;8646.178613031752;4522.412086428743;4585.10590434862;4588.403507544064;4619.4687610925175;4622.422197225347;1683.6951362693765;1754.9561260968578;1866.132248504452;1869.6167285706397;1924.7305377247196;810.1898101898101;925.8148370325962;949.4208532794989;1011.9121943910326;1047.3611097230682;20676.379708608765;21247.256346027814;21364.252251171136;21394.251201207884;21459.390545709135;837.6481877611418;842.3219308456629;920.6179435534178;933.295362001698;935.8175155844681;10934.617288394868;11120.777584448266;11877.524899003944;11955.820662690066;12273.754524909451;488139.5593022012;600609;600750;612584;618003;142620;144142;145423;147015;148186;956.416585882611;966.6375109334;990.3166814897697;990.9405435673739;1008.9960489313033;19308.4207473776;19659.115339809458;19715.80284197197;19805;19833.702494462304;42998;43989;55769.1634625489;58362.708186458796;58893.70553147378;79140;84350;85600.1439985617;85980;88844.5557772207;8208.712695055645;8224.83550329002;8302.792430189294;8530.976282846099;8541.53021583819;12368.623301959484;13237.470501179845;13320.733585328046;13328.866711332763;13371.866281337063;11155.609553665583;11219.71950701239;11276.7744645105;11385.886141138482;11593.82609260845;9249.676261330822;9467.905320946702;11123.944380278046;11465.885341146815;11499.712507187387;10190.592376304865;11597.18819682614;11682;11744.00175985051;11888.28670279786;8278.71024514139;8328.875066874123;8338.37462190326;8365.163483651586;8412.327013838756;10786.784264314827;11251.324920504796;11328.94335528317;11402.94298528502;11417.486213120595 06:06:27 INFO - PROCESS | 1656 | |3;mootools.html;1480.222882986432;1485.5840364697885;1701.9319227230912;1846.593749375137;1890.7447494588228;8486.745397638073;8765.430247033953;8957.73126806196;8965.551722413878;8972.91027089729;7817.726379576716;8057.19428057194;8527.104654011333;8761.95619021904;8773.034966153718;2357.3045951444324;2385.427497400629;2389.307100940725;2392.4018995251186;2392.5932591459405;2098.27609474731;2351.1535847095033;2354.411397150712;2389.880505974703;2390.6653068570413;2147.6134295826746;2282.8972696228625;2370.277065495023;2376.6791483149814;2378.4529558201625;2230.4200907763934;2232.520008198229;2236.0943817753837;2337.2988103568946;2362.173239366225;2332.288651961151;2339.3332900123382;2357.6463530470396;2362.267697013931;2370.1230544698574;8717.389782715212;8779.956100219459;8780.121987801233;8798.384113112084;8812.647494100229;5202.193659982709;5402.864928376783;5413.458654134594;5414.810481633163;5423.728813559306;3503.6321186275327;3592.3354179476883;3650.123970247148;3677.9448308275396;3683.1344634010984;1001.6744558018673;1018.9592416303338;1025.933314334566;1039.6517166749147;1045.0907710292095;11346.092312614972;11554.711132221677;11563.942180289128;11576.884231157665;11596.826047609293;4552.7723613819835;4575.519570445122;4794.2089555223;4799.496052914394;4807.519248075235;10009.899901001043;10017.398956062514;10027.899721002843;10030.799384012425;10044.045815647502;5260.500252476085;5331.760070796823;5335.706536140549;5336.679799211982;5337.412884582693;4124.195781822576;4731.4795372509;4884.3650325458175;4892.437369702529;4900.411950565884;3835.328817456934;3847.7306588539095;3877.9806100969313;3923.9803800981385;3934.213157368538;1042.671558459083;1190.7678002789373;1236.202649291202;1274.1081243129847;1275.2730943362194;898.8292224477328;903.8915330160423;915.665781989569;931.4039015030386;939.572494514994;5388.865278368072;5724.856878578069;5851.008045385724;5864.941350586525;5930.7924222652;923.3951761596031;945.5555888732284;959.5058544849409;974.8586454964133;980.548947484151;3475.391806433864;3900.258950799339;3956.940645890314;3972.5431575368625;3991.980040099839;10178.643747468952;10868.45657717111;10883.83674244887;10884.346939183675;10888.020078193;10621.628243011457;10670.946645266724;10829.458527073615;10849.728756780985;10898.182636302308;8313.6674533018;8370.079291278076;8379.287760540301;8399.286060684788;8420.242178203991;5749.626274292157;5798.536117110537;5835.358110607915;5872.647641141545;5908.113782932594;9784.706458806248;10066.245031622804;10084.193264538966;10221.795564088825;10230.59077636916;9493.525323733924;10438.425886576311;10472.68581942543;10487.108595769445;10498.47507624631;7580.431467639839;7585.431092667962;7669.616519173907;7673.117591476828;7676.193999630182;8213.342932565261;8370.497770133812;8406.789830254047;8415.663373464993;8424.410291279552;7560.4329675273475;7570.5836179010685;7580.014598102432;7668.57822819728;7734.729284475016;7433.8141546461775;7449.515781474187;7462.179160292472;7583.544987300779;7593.392528597588;6772.86454270902;7070.575765454089;7078.221395646372;7138.428925686037;7143.571385716874;4918.4097908249805;4996.325496057985;5018.372703412074;5038.773255203451;5140.357455318085;5012.523810238022;5105.897882042413;5107.107815060245;5133.435322114639;5258.553022993088 06:06:27 INFO - PROCESS | 1656 | |4;prototype.html;277.7293880496728;280.12042187531154;295.5315824418297;304.05135975755644;306.79138186033504;4283.764392958394;4659.207934651105;5148.768305426255;5169.638125331228;5171.870703232421;4947.812524994002;5159.974200129003;5163.845084647466;5165.457626949173;5174.611904107198;3988;4002.2395744808537;4005.9184020314497;4007.0783719744477;4023.41660459234;3740.0836794985266;3826.9808650956847;3993.680505559563;4020.3315624016;4023.2355852387955;3200;3475.7393195510313;3850.306944749944;4010.9799451002705;4026.530316434495;2905.840178790176;3495.4057810172203;3927.5483319418345;3983.223271462066;3983.9084480647603;3472.218750781054;3472.826358682056;3474.6872781449792;3477.4957631143598;3478.843452044664;4390.8902277443;4809.158397280462;4840.975795121002;5072.264521644341;5074.644774865761;4529.592336689714;4530.4143549757655;4547.931781023287;4567.543245675439;4580.564846339593;5440.945590544084;5446.459801129258;5470.6444081135;5477.493689235453;5500.532454741352;3699.981500092509;3830.6743926766258;3843.0968722350226;3862.6909847212437;3871.903202419962;5209.062368773619;5690.630109042899;5712.971435142797;5732.713364331767;5745.626534275259;3903.4535165076913;3906.1797022625;3909.941350879682;3911.5501717302873;3913.412988051815;3292.3909076820864;3833.15670552478;3966.7619942803526;3967.8294903003684;3974.8608798692485;3741.7006639469323;4481.395011673382;4520.841770538015;4537.956270057905;4549.886252843706;5260.815871444481;5505.587080968943;5508.834734957958;5526.198701188305;5539.064295923167;1246.956356527517;1255.0837888341562;1276.2278821313157;1277.3740866975115;1349.2107117336468;783;826.7147833997261;897.9955100224457;927.846905260638;938.957746901391;4412.757298348557;4671.11248862715;5113.53978141969;5135.717535535507;5156.690598564098;5043;5289.523942845162;5618.494335509824;5658.91511627326;5664.037113690682;4797.664163508521;4971.701697898137;5031.245313203049;5063.974680126576;5145;2649.6555447791666;2748;2876.8561571921737;2934.1784300395925;2938.266422810509;4503.636554233851;5029.396472423259;5662.292213473316;5775.768969241183;5793.8551536211935;4294.828206871753;5541.778328866801;5647.463490968352;5789.899919015374;5791.148885990769;5222.668219604053;5345.9465405347;5525.226468294442;5776.33572139201;5792.739326730307;4858.90282194347;5279.26090347344;5604.971975140262;5606.691631960118;5642.605017648725;5431.071969450788;5514.61397702157;5544.889102218014;5546.694931778791;5547.084731019337;5026.271190677405;5458.563314934875;5774.39368866263;5793.478586927196;5795.652260864362;2942.7346241116525;3837.9424308635953;3898.6686131679126;3905.2970465316516;3915.138669492707;4858.684185527929;5425.697832520245;5512.448755124456;5571.721413929206;5586.804461843816;5111.948880511147;5424.050791111461;5426.612290592127;5431.2667789847865;5453.700046497322;4713.175194341045;4959.876003099951;5394.300795249484;5417.968261901787;5437.4018857926385;4679.578837904605;4719.716816990992;4918.69654541554;4924.76880779805;5295.867603309948;4211.810468528863;5230;5454.699991500347;5494.203340515683;5494.313210848644;4415.933760993524;5387.649802762808;5400.702961337164;5423.213634023124;5439.156930675752 06:06:27 INFO - PROCESS | 1656 | |5;yui.html;663.7610460234315;762.1844626249912;787.4684587903166;791.8099656082542;831.6507067031846;15047.172405517693;16122.838771612265;16139.919300403511;16174.2721577529;16364.936279141877;14453;14622.780658290107;14633.634159146026;15341.76987345188;16116.274767635456;817.9631916563769;843.6203708331245;845.9196376344238;846.2679781988597;848.0120659431755;821.0885916632533;834.1616675241374;834.6285902773268;835.0230230630177;836.2891542189151;890.4790697442004;892.4199270474179;892.723255790707;893.3746377535751;894.6197865906996;883.2933653077577;883.567052144451;885.6191837509868;885.8848349714528;888.0364804187465;650.4698670583507;651.9087327774115;652.5921299188008;653.0367707631253;657.2047822135222;14769.70460590793;15214.391424343125;15258.00822946505;15271.618209544738;15951.122688252142;13774.242416667079;14010.719785604333;14116.294185290797;14163.716725665428;14366.497172599013;11802.11484138693;12102.455389507493;12468.501259949591;12482.81275780864;12508;3705.555333360014;3776.848926042955;3854.2869569129803;3896.0649444133487;3949.3483575210057;110562;110638.44680776625;113628;118002;118427;10939.507722152523;10947.397893115796;11040.392778397265;11080.556777728962;11538;21648.675269870964;21930.45173870666;21938.561228775336;21955.560888782136;21955.780442195693;7766.922330776733;7883.881741773878;7932;7957.283844454027;8191.0580283267;4370.590939067532;4385.934210986838;4452.955470445254;4613.746243956614;4622.791974361095;3467.2718729066673;3598.3882739934274;3603.549556305462;3603.819809009539;3609.8556057757396;607.1131768441122;616.4975544930867;625.0499241153422;625.1060982794556;625.8466675664491;519.2548692626094;551.9613627046068;577.8439821248239;589.381149792716;662.3740565165969;8711.738647840575;9333.43999360027;9447.149756521945;9489.193418559362;9597.136257736698;552.7706002009202;590.10598942602;621.4624349937362;624.3849807939176;706.9257727938596;4591;4601.44782626087;4656.976715116402;4679.859604211879;4756.833510827105;142077;155957;156234;156539;163009.18495407683;79853;117234.41382793032;129811;132905;134819;115827.84172158339;119123;119274;120279;123575;9128.589213485542;9813.950930245303;10123.848142277724;10155.746106347106;10195.694129176136;46400;57611.71194144002;59850;60069.69965150146;60263;88114;89291.21417571742;90889.0911090907;91701;91934.54032730061;11706.35615041181;11998;13119.27843968591;13177.27524986135;13389.25939627881;14834.10995340283;14948.25258737037;15080;15136.167510787012;15874.126923019345;13837.584872453843;13960.790588140982;14059.22674252885;14516.854831451972;14538.563843084725;12220.144589878979;13544.59366219015;13611.931940340235;14142.43430262778;14202;13216.207027578375;13559.932200338935;13603.183808971493;13707.657308567366;14000.859991400363;11179.944100279447;11501.942490287494;11588.826167607323;11687.298762074302;12020;11040.447977600927;11399.20205585601;11441.48513316886;11582.305061696325;11700.531978720755 06:06:27 INFO - PROCESS | 1656 | __end_tp_report 06:06:27 INFO - PROCESS | 1656 | __start_cc_report 06:06:27 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1519 06:06:27 INFO - PROCESS | 1656 | __end_cc_report 06:06:27 INFO - PROCESS | 1656 | __startTimestamp1467291987417__endTimestamp 06:06:27 INFO - PROCESS | 1656 | 06:06:27 INFO - PROCESS | 1656 | ------- Summary: start ------- 06:06:27 INFO - PROCESS | 1656 | Number of tests: 6 06:06:27 INFO - PROCESS | 1656 | 06:06:27 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5641.76 Median:5783.13 stddev:656.27 (11.3%) stddev-sans-first:619.30 06:06:27 INFO - PROCESS | 1656 | Values: 2801.4 3310.5 3347.1 3374.9 3382.3 4472.5 5528.0 5545.0 5575.5 5629.5 5564.0 5593.0 5597.6 5634.7 5672.8 5051.1 5108.2 5154.8 5166.2 5302.1 5200.1 5210.9 5222.4 5234.1 5240.5 5067.8 5079.3 5080.3 5102.8 5119.2 5093.5 5106.0 5159.5 5179.0 5189.9 5092.0 5127.5 5130.5 5176.3 5196.4 5469.0 5544.6 5550.3 5602.1 5611.1 6139.0 6154.3 6169.6 6187.9 6195.8 6058.0 6175.3 6226.8 6259.0 6265.5 5635.2 5698.3 5718.2 5722.2 5780.2 6274.4 6304.3 6306.1 6316.0 6317.0 5877.4 5954.3 6017.5 6055.4 6055.9 6147.5 6254.4 6262.7 6264.0 6265.0 6113.4 6115.2 6161.4 6174.5 6180.3 4438.5 5651.6 6024.1 6100.8 6195.6 4925.5 4942.9 4950.2 4954.0 4989.3 4401.0 4404.3 4412.9 4414.7 4433.0 5483.0 5516.3 5520.3 5597.7 5600.0 5097.4 6108.6 6134.5 6162.6 6168.0 5520.6 5586.4 5587.7 5587.9 5594.8 4581.9 5379.9 5390.8 5394.9 5407.6 6279.9 6300.3 6300.3 6325.0 6334.6 6023.7 6077.4 6124.7 6235.7 6240.5 6251.6 6321.5 6326.2 6333.0 6333.3 6134.5 6136.2 6139.5 6144.3 6168.9 6059.6 6112.2 6230.1 6239.6 6253.7 5927.5 5953.0 5991.8 6014.8 6023.9 5927.6 5945.8 5948.6 5954.0 5988.1 5657.7 5677.3 5761.7 5777.9 5783.1 5831.9 5893.5 5921.4 5948.7 6001.6 5850.1 5876.9 5886.4 5899.2 5963.0 06:06:27 INFO - PROCESS | 1656 | 06:06:27 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:65342.19 Median:21914.50 stddev:132963.75 (606.7%) stddev-sans-first:133247.68 06:06:27 INFO - PROCESS | 1656 | Values: 523.9 527.0 534.7 549.4 575.6 30408.7 41394.4 41654.6 42061.8 44897.8 43669.1 43869.8 43902.6 43905.3 43989.3 8728.7 9623.1 9821.0 9821.6 9824.7 8732.6 8914.5 8923.2 8925.2 11727.6 11701.1 11984.5 12021.6 12029.2 12064.9 11860.8 11906.3 11924.1 11925.7 11944.3 8708.2 11474.3 11646.9 11781.9 11852.1 41287.8 41459.4 41486.6 41519.2 41758.0 2889.5 3594.0 3916.0 4123.7 4147.3 31304.0 32567.5 32893.5 32914.5 33008.2 3254.6 3325.4 3366.2 3411.8 3455.6 477034.0 480022.0 482354.2 483278.0 485602.0 60424.1 60656.0 60716.7 60833.7 60919.0 52820.0 64175.7 64268.7 64337.7 64468.7 16553.8 16812.9 16836.3 16843.5 16885.0 13021.7 13027.9 13069.5 13077.7 13093.1 15109.2 15583.4 16916.9 17527.7 17550.9 7257.8 7341.6 7364.3 7413.2 7487.7 909.4 929.6 942.0 1013.2 1093.7 30127.8 30196.0 31142.0 31398.2 31455.5 1024.6 1101.2 1135.9 1153.0 1160.3 15760.9 15778.9 15874.8 16114.5 16210.4 545455.3 550277.0 550885.0 552346.0 553648.0 59902.7 59917.4 59936.7 60244.4 60245.7 1350.6 1389.2 1415.2 1415.4 1416.1 30135.5 30349.1 30352.8 30854.7 31763.0 50978.2 51234.7 51375.7 51391.7 51417.0 465012.0 466572.0 466613.0 466669.0 466874.0 35353.5 41930.6 42340.4 42710.6 42812.4 48058.3 48320.5 48581.3 49296.5 49450.0 33404.7 38847.0 40633.6 41068.0 41155.0 30731.4 31016.1 39913.0 40128.8 41106.8 38727.2 38868.8 38889.0 39123.0 39159.0 33996.3 34444.8 35576.5 35582.8 35746.6 25803.6 25859.5 25953.7 26021.7 26324.2 06:06:27 INFO - PROCESS | 1656 | 06:06:27 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:35101.42 Median:13482.62 stddev:98583.16 (731.2%) stddev-sans-first:98825.19 06:06:27 INFO - PROCESS | 1656 | Values: 354.8 359.5 387.7 390.2 409.0 15739.4 15803.4 20537.6 20581.3 20789.2 17489.3 19308.7 20137.8 20952.7 20987.1 5005.5 5349.8 5517.2 5518.1 6078.9 5979.8 6013.1 6036.7 6037.8 6050.6 5830.8 5916.6 5923.9 5966.3 5968.6 5861.0 5903.6 6000.4 6018.0 6024.6 4993.1 4996.6 5000.1 5003.7 5042.5 13193.7 13710.6 13711.7 23219.0 24469.6 2495.6 2512.9 2599.3 2624.1 2738.6 10098.4 10162.2 10312.8 10455.9 15850.8 1809.1 1837.7 1889.9 1890.8 1943.8 109829.5 111619.0 122016.2 124039.8 124813.0 11189.8 11438.8 11570.5 11592.6 11800.5 11757.9 14517.5 14520.2 14545.9 14590.4 8368.7 8553.2 8555.7 8661.5 8690.7 6905.6 6959.3 8562.1 8641.4 8646.2 4522.4 4585.1 4588.4 4619.5 4622.4 1683.7 1755.0 1866.1 1869.6 1924.7 810.2 925.8 949.4 1011.9 1047.4 20676.4 21247.3 21364.3 21394.3 21459.4 837.6 842.3 920.6 933.3 935.8 10934.6 11120.8 11877.5 11955.8 12273.8 488139.6 600609.0 600750.0 612584.0 618003.0 142620.0 144142.0 145423.0 147015.0 148186.0 956.4 966.6 990.3 990.9 1009.0 19308.4 19659.1 19715.8 19805.0 19833.7 42998.0 43989.0 55769.2 58362.7 58893.7 79140.0 84350.0 85600.1 85980.0 88844.6 8208.7 8224.8 8302.8 8531.0 8541.5 12368.6 13237.5 13320.7 13328.9 13371.9 11155.6 11219.7 11276.8 11385.9 11593.8 9249.7 9467.9 11123.9 11465.9 11499.7 10190.6 11597.2 11682.0 11744.0 11888.3 8278.7 8328.9 8338.4 8365.2 8412.3 10786.8 11251.3 11328.9 11402.9 11417.5 06:06:27 INFO - PROCESS | 1656 | 06:06:27 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5738.76 Median:4888.40 stddev:3225.21 (66.0%) stddev-sans-first:3218.38 06:06:27 INFO - PROCESS | 1656 | Values: 1480.2 1485.6 1701.9 1846.6 1890.7 8486.7 8765.4 8957.7 8965.6 8972.9 7817.7 8057.2 8527.1 8762.0 8773.0 2357.3 2385.4 2389.3 2392.4 2392.6 2098.3 2351.2 2354.4 2389.9 2390.7 2147.6 2282.9 2370.3 2376.7 2378.5 2230.4 2232.5 2236.1 2337.3 2362.2 2332.3 2339.3 2357.6 2362.3 2370.1 8717.4 8780.0 8780.1 8798.4 8812.6 5202.2 5402.9 5413.5 5414.8 5423.7 3503.6 3592.3 3650.1 3677.9 3683.1 1001.7 1019.0 1025.9 1039.7 1045.1 11346.1 11554.7 11563.9 11576.9 11596.8 4552.8 4575.5 4794.2 4799.5 4807.5 10009.9 10017.4 10027.9 10030.8 10044.0 5260.5 5331.8 5335.7 5336.7 5337.4 4124.2 4731.5 4884.4 4892.4 4900.4 3835.3 3847.7 3878.0 3924.0 3934.2 1042.7 1190.8 1236.2 1274.1 1275.3 898.8 903.9 915.7 931.4 939.6 5388.9 5724.9 5851.0 5864.9 5930.8 923.4 945.6 959.5 974.9 980.5 3475.4 3900.3 3956.9 3972.5 3992.0 10178.6 10868.5 10883.8 10884.3 10888.0 10621.6 10670.9 10829.5 10849.7 10898.2 8313.7 8370.1 8379.3 8399.3 8420.2 5749.6 5798.5 5835.4 5872.6 5908.1 9784.7 10066.2 10084.2 10221.8 10230.6 9493.5 10438.4 10472.7 10487.1 10498.5 7580.4 7585.4 7669.6 7673.1 7676.2 8213.3 8370.5 8406.8 8415.7 8424.4 7560.4 7570.6 7580.0 7668.6 7734.7 7433.8 7449.5 7462.2 7583.5 7593.4 6772.9 7070.6 7078.2 7138.4 7143.6 4918.4 4996.3 5018.4 5038.8 5140.4 5012.5 5105.9 5107.1 5133.4 5258.6 06:06:27 INFO - PROCESS | 1656 | 06:06:27 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4416.34 Median:4965.79 stddev:1347.45 (27.1%) stddev-sans-first:1314.94 06:06:27 INFO - PROCESS | 1656 | Values: 277.7 280.1 295.5 304.1 306.8 4283.8 4659.2 5148.8 5169.6 5171.9 4947.8 5160.0 5163.8 5165.5 5174.6 3988.0 4002.2 4005.9 4007.1 4023.4 3740.1 3827.0 3993.7 4020.3 4023.2 3200.0 3475.7 3850.3 4011.0 4026.5 2905.8 3495.4 3927.5 3983.2 3983.9 3472.2 3472.8 3474.7 3477.5 3478.8 4390.9 4809.2 4841.0 5072.3 5074.6 4529.6 4530.4 4547.9 4567.5 4580.6 5440.9 5446.5 5470.6 5477.5 5500.5 3700.0 3830.7 3843.1 3862.7 3871.9 5209.1 5690.6 5713.0 5732.7 5745.6 3903.5 3906.2 3909.9 3911.6 3913.4 3292.4 3833.2 3966.8 3967.8 3974.9 3741.7 4481.4 4520.8 4538.0 4549.9 5260.8 5505.6 5508.8 5526.2 5539.1 1247.0 1255.1 1276.2 1277.4 1349.2 783.0 826.7 898.0 927.8 939.0 4412.8 4671.1 5113.5 5135.7 5156.7 5043.0 5289.5 5618.5 5658.9 5664.0 4797.7 4971.7 5031.2 5064.0 5145.0 2649.7 2748.0 2876.9 2934.2 2938.3 4503.6 5029.4 5662.3 5775.8 5793.9 4294.8 5541.8 5647.5 5789.9 5791.1 5222.7 5345.9 5525.2 5776.3 5792.7 4858.9 5279.3 5605.0 5606.7 5642.6 5431.1 5514.6 5544.9 5546.7 5547.1 5026.3 5458.6 5774.4 5793.5 5795.7 2942.7 3837.9 3898.7 3905.3 3915.1 4858.7 5425.7 5512.4 5571.7 5586.8 5111.9 5424.1 5426.6 5431.3 5453.7 4713.2 4959.9 5394.3 5418.0 5437.4 4679.6 4719.7 4918.7 4924.8 5295.9 4211.8 5230.0 5454.7 5494.2 5494.3 4415.9 5387.6 5400.7 5423.2 5439.2 06:06:27 INFO - PROCESS | 1656 | 06:06:27 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:25084.74 Median:21955.67 stddev:40228.15 (183.2%) stddev-sans-first:40299.21 06:06:27 INFO - PROCESS | 1656 | Values: 663.8 762.2 787.5 791.8 831.7 15047.2 16122.8 16139.9 16174.3 16364.9 14453.0 14622.8 14633.6 15341.8 16116.3 818.0 843.6 845.9 846.3 848.0 821.1 834.2 834.6 835.0 836.3 890.5 892.4 892.7 893.4 894.6 883.3 883.6 885.6 885.9 888.0 650.5 651.9 652.6 653.0 657.2 14769.7 15214.4 15258.0 15271.6 15951.1 13774.2 14010.7 14116.3 14163.7 14366.5 11802.1 12102.5 12468.5 12482.8 12508.0 3705.6 3776.8 3854.3 3896.1 3949.3 110562.0 110638.4 113628.0 118002.0 118427.0 10939.5 10947.4 11040.4 11080.6 11538.0 21648.7 21930.5 21938.6 21955.6 21955.8 7766.9 7883.9 7932.0 7957.3 8191.1 4370.6 4385.9 4453.0 4613.7 4622.8 3467.3 3598.4 3603.5 3603.8 3609.9 607.1 616.5 625.0 625.1 625.8 519.3 552.0 577.8 589.4 662.4 8711.7 9333.4 9447.1 9489.2 9597.1 552.8 590.1 621.5 624.4 706.9 4591.0 4601.4 4657.0 4679.9 4756.8 142077.0 155957.0 156234.0 156539.0 163009.2 79853.0 117234.4 129811.0 132905.0 134819.0 115827.8 119123.0 119274.0 120279.0 123575.0 9128.6 9814.0 10123.8 10155.7 10195.7 46400.0 57611.7 59850.0 60069.7 60263.0 88114.0 89291.2 90889.1 91701.0 91934.5 11706.4 11998.0 13119.3 13177.3 13389.3 14834.1 14948.3 15080.0 15136.2 15874.1 13837.6 13960.8 14059.2 14516.9 14538.6 12220.1 13544.6 13611.9 14142.4 14202.0 13216.2 13559.9 13603.2 13707.7 14000.9 11179.9 11501.9 11588.8 11687.3 12020.0 11040.4 11399.2 11441.5 11582.3 11700.5 06:06:27 INFO - PROCESS | 1656 | -------- Summary: end -------- 06:06:27 INFO - PROCESS | 1656 | 06:06:27 INFO - TEST-INFO | 1656: exit 0 06:06:27 INFO - TEST-OK | dromaeo_css | took 1114891ms 06:06:27 INFO - TEST-START | kraken 06:06:27 INFO - operating with platform_type : mac_ 06:06:27 INFO - Initialising browser for kraken test... 06:06:27 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmp5I9_6Q/profile http://localhost:49272/getInfo.html) 06:06:30 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 06:06:30 INFO - PROCESS | 1664 | colorDepth:24 06:06:30 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 06:06:30 INFO - PROCESS | 1664 | __metrics 06:06:30 INFO - PROCESS | 1664 | 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" 06:06:30 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:06:30 INFO - TEST-INFO | 1664: exit 0 06:06:30 INFO - Browser initialized. 06:06:30 INFO - Running cycle 1/1 for kraken test... 06:06:30 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/tmp5I9_6Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:06:42 INFO - PROCESS | 1665 | RSS: Main: 244617216 06:06:42 INFO - PROCESS | 1665 | 06:06:45 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 06:06:46 INFO - PROCESS | 1665 | RSS: Main: 294264832 06:06:46 INFO - PROCESS | 1665 | 06:06:48 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 06:06:49 INFO - PROCESS | 1665 | RSS: Main: 304418816 06:06:49 INFO - PROCESS | 1665 | 06:06:53 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 06:06:53 INFO - PROCESS | 1665 | RSS: Main: 386764800 06:06:53 INFO - PROCESS | 1665 | 06:06:55 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 06:06:56 INFO - PROCESS | 1665 | RSS: Main: 288894976 06:06:56 INFO - PROCESS | 1665 | 06:06:58 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:06:58 INFO - PROCESS | 1665 | RSS: Main: 293232640 06:06:58 INFO - PROCESS | 1665 | 06:07:01 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 06:07:02 INFO - PROCESS | 1665 | RSS: Main: 330801152 06:07:02 INFO - PROCESS | 1665 | 06:07:05 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 06:07:05 INFO - PROCESS | 1665 | RSS: Main: 331509760 06:07:05 INFO - PROCESS | 1665 | 06:07:08 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 06:07:09 INFO - PROCESS | 1665 | RSS: Main: 331382784 06:07:09 INFO - PROCESS | 1665 | 06:07:10 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:07:11 INFO - PROCESS | 1665 | RSS: Main: 311799808 06:07:11 INFO - PROCESS | 1665 | 06:07:13 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:07:13 INFO - PROCESS | 1665 | RSS: Main: 319365120 06:07:13 INFO - PROCESS | 1665 | 06:07:15 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:07:16 INFO - PROCESS | 1665 | RSS: Main: 317222912 06:07:16 INFO - PROCESS | 1665 | 06:07:19 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:07:20 INFO - PROCESS | 1665 | RSS: Main: 326299648 06:07:20 INFO - PROCESS | 1665 | 06:07:23 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:07:23 INFO - PROCESS | 1665 | RSS: Main: 328871936 06:07:23 INFO - PROCESS | 1665 | 06:07:25 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 06:07:25 INFO - PROCESS | 1665 | RSS: Main: 371834880 06:07:25 INFO - PROCESS | 1665 | 06:07:25 INFO - PROCESS | 1665 | __start_tp_report 06:07:25 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 06:07:25 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 06:07:25 INFO - PROCESS | 1665 | |i|pagename|runs| 06:07:25 INFO - PROCESS | 1665 | |0;ai-astar;102;113;113;112;113;113;113;113;113;113 06:07:25 INFO - PROCESS | 1665 | |1;audio-beat-detection;144;114;106;156;107;107;124;110;140;107 06:07:25 INFO - PROCESS | 1665 | |2;audio-dft;182;184;183;184;183;184;181;182;183;183 06:07:25 INFO - PROCESS | 1665 | |3;audio-fft;80;73;58;106;59;57;63;68;82;57 06:07:25 INFO - PROCESS | 1665 | |4;audio-oscillator;84;95;89;71;71;70;94;70;68;100 06:07:25 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;89;85;88;85;88;84;88;86;88;85 06:07:25 INFO - PROCESS | 1665 | |6;imaging-darkroom;93;94;94;94;93;93;94;95;93;93 06:07:25 INFO - PROCESS | 1665 | |7;imaging-desaturate;86;86;92;84;84;84;84;85;86;85 06:07:25 INFO - PROCESS | 1665 | |8;json-parse-financial;55;53;52;54;54;53;55;54;54;53 06:07:25 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;66;71;74;77;75;75;71;69;70;71 06:07:25 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;83;84;84;84;85;83;81;86;81;82 06:07:25 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;152;151;138;139;145;137;148;151;247;138 06:07:25 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;172;172;172;172;172;172;173;171;173;170 06:07:25 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;67;68;68;68;67;68;71;73;88;86 06:07:25 INFO - PROCESS | 1665 | __end_tp_report 06:07:25 INFO - PROCESS | 1665 | __start_cc_report 06:07:25 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2016 06:07:25 INFO - PROCESS | 1665 | __end_cc_report 06:07:25 INFO - PROCESS | 1665 | __startTimestamp1467292045760__endTimestamp 06:07:25 INFO - PROCESS | 1665 | 06:07:25 INFO - PROCESS | 1665 | ------- Summary: start ------- 06:07:25 INFO - PROCESS | 1665 | Number of tests: 14 06:07:25 INFO - PROCESS | 1665 | 06:07:25 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:3.46 (3.1%) stddev-sans-first:0.33 06:07:25 INFO - PROCESS | 1665 | Values: 102.0 113.0 113.0 112.0 113.0 113.0 113.0 113.0 113.0 113.0 06:07:25 INFO - PROCESS | 1665 | 06:07:25 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:121.50 Median:119.00 stddev:18.57 (15.6%) stddev-sans-first:17.83 06:07:25 INFO - PROCESS | 1665 | Values: 144.0 114.0 106.0 156.0 107.0 107.0 124.0 110.0 140.0 107.0 06:07:25 INFO - PROCESS | 1665 | 06:07:25 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.00 stddev:0.99 (0.5%) stddev-sans-first:1.00 06:07:25 INFO - PROCESS | 1665 | Values: 182.0 184.0 183.0 184.0 183.0 184.0 181.0 182.0 183.0 183.0 06:07:25 INFO - PROCESS | 1665 | 06:07:25 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:70.30 Median:65.50 stddev:15.65 (23.9%) stddev-sans-first:16.20 06:07:25 INFO - PROCESS | 1665 | Values: 80.0 73.0 58.0 106.0 59.0 57.0 63.0 68.0 82.0 57.0 06:07:25 INFO - PROCESS | 1665 | 06:07:25 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:81.20 Median:77.50 stddev:12.51 (16.1%) stddev-sans-first:13.23 06:07:25 INFO - PROCESS | 1665 | Values: 84.0 95.0 89.0 71.0 71.0 70.0 94.0 70.0 68.0 100.0 06:07:25 INFO - PROCESS | 1665 | 06:07:25 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:88.00 stddev:1.78 (2.0%) stddev-sans-first:1.66 06:07:25 INFO - PROCESS | 1665 | Values: 89.0 85.0 88.0 85.0 88.0 84.0 88.0 86.0 88.0 85.0 06:07:25 INFO - PROCESS | 1665 | 06:07:25 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 06:07:25 INFO - PROCESS | 1665 | Values: 93.0 94.0 94.0 94.0 93.0 93.0 94.0 95.0 93.0 93.0 06:07:25 INFO - PROCESS | 1665 | 06:07:25 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:85.50 stddev:2.41 (2.8%) stddev-sans-first:2.55 06:07:25 INFO - PROCESS | 1665 | Values: 86.0 86.0 92.0 84.0 84.0 84.0 84.0 85.0 86.0 85.0 06:07:25 INFO - PROCESS | 1665 | 06:07:25 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:0.95 (1.8%) stddev-sans-first:0.88 06:07:25 INFO - PROCESS | 1665 | Values: 55.0 53.0 52.0 54.0 54.0 53.0 55.0 54.0 54.0 53.0 06:07:25 INFO - PROCESS | 1665 | 06:07:25 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:72.50 stddev:3.31 (4.6%) stddev-sans-first:2.74 06:07:25 INFO - PROCESS | 1665 | Values: 66.0 71.0 74.0 77.0 75.0 75.0 71.0 69.0 70.0 71.0 06:07:25 INFO - PROCESS | 1665 | 06:07:25 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:84.00 stddev:1.64 (1.9%) stddev-sans-first:1.73 06:07:25 INFO - PROCESS | 1665 | Values: 83.0 84.0 84.0 84.0 85.0 83.0 81.0 86.0 81.0 82.0 06:07:25 INFO - PROCESS | 1665 | 06:07:25 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:154.60 Median:149.50 stddev:33.02 (22.1%) stddev-sans-first:35.01 06:07:25 INFO - PROCESS | 1665 | Values: 152.0 151.0 138.0 139.0 145.0 137.0 148.0 151.0 247.0 138.0 06:07:25 INFO - PROCESS | 1665 | 06:07:25 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:172.00 stddev:0.88 (0.5%) stddev-sans-first:0.93 06:07:25 INFO - PROCESS | 1665 | Values: 172.0 172.0 172.0 172.0 172.0 172.0 173.0 171.0 173.0 170.0 06:07:25 INFO - PROCESS | 1665 | 06:07:25 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.40 Median:69.50 stddev:7.93 (11.4%) stddev-sans-first:8.17 06:07:25 INFO - PROCESS | 1665 | Values: 67.0 68.0 68.0 68.0 67.0 68.0 71.0 73.0 88.0 86.0 06:07:25 INFO - PROCESS | 1665 | -------- Summary: end -------- 06:07:25 INFO - PROCESS | 1665 | 06:07:26 INFO - TEST-INFO | 1665: exit 0 06:07:26 INFO - TEST-OK | kraken | took 58590ms 06:07:26 INFO - SUITE-END | took 1173s 06:07:26 INFO - Completed test suite (00:19:33) 06:07:26 INFO - javascript benchmark 06:07:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2801.369691819341, 3310.5034244863273, 3347.113015051011, 3374.881879134231, 3382.2559037011847, 4472.485664148624, 5528.0, 5544.972275138623, 5575.498205161534, 5629.549636029124, 5563.998480273549, 5592.972035139818, 5597.63615365001, 5634.690092044946, 5672.829815105543, 5051.1413059779925, 5108.208227724708, 5154.793808247666, 5166.225066240057, 5302.125149350352, 5200.063988482072, 5210.921836172461, 5222.399424066224, 5234.136367499358, 5240.502152295545, 5067.797288108472, 5079.314292570492, 5080.314157588734, 5102.770375333119, 5119.206522988943, 5093.5415812576675, 5106.0, 5159.51869788804, 5179.041877252683, 5189.870253243661, 5092.032513822362, 5127.5385215330425, 5130.486951304877, 5176.275321454999, 5196.350456192976, 5469.0, 5544.6118771686, 5550.250716153308, 5602.13166959122, 5611.130274807411, 6139.017757158877, 6154.292256390528, 6169.629822210637, 6187.907181392283, 6195.845103872394, 6058.030715085565, 6175.258968923713, 6226.750929962796, 6258.968705156445, 6265.530085243579, 5635.154726790973, 5698.287714035746, 5718.170865224519, 5722.170285308607, 5780.248567686258, 6274.435300822948, 6304.275008374096, 6306.148669929593, 6315.96842015787, 6317.0, 5877.441643043904, 5954.345022047572, 6017.548683848729, 6055.394460553909, 6055.878882422327, 6147.538934579924, 6254.437100660963, 6262.6868656566985, 6264.0, 6264.968675156595, 6113.388661133851, 6115.1744514490865, 6161.383861613893, 6174.5060395168275, 6180.3201647818705, 4438.467383913887, 5651.57613179005, 6024.096385542204, 6100.847478813065, 6195.628262304276, 4925.482824303468, 4942.8764280893265, 4950.183219768698, 4953.975230123826, 4989.276554899519, 4400.977995110068, 4404.339349097597, 4412.867613971586, 4414.735115892992, 4433.046894917608, 5482.972585137129, 5516.282883225235, 5520.254765606592, 5597.692126932976, 5600.0, 5097.439281679012, 6108.615274519878, 6134.4978165938865, 6162.599431037058, 6168.0439531872635, 5520.641158324696, 5586.357568879631, 5587.664740115606, 5587.860303492445, 5594.804181853616, 4581.862544123682, 5379.919301210485, 5390.757415916215, 5394.91907621386, 5407.5944304176555, 6279.905801413074, 6300.306966233802, 6300.338464461165, 6325.0, 6334.619922804646, 6023.728932197974, 6077.4226448487325, 6124.74712746768, 6235.688215589294, 6240.53196010292, 6251.593646412968, 6321.462675672619, 6326.177596912556, 6333.018382150774, 6333.334999825136, 6134.478569321657, 6136.171616831671, 6139.508839292756, 6144.293406258337, 6168.907466387918, 6059.6364218147055, 6112.205413296243, 6230.127782110599, 6239.594426362272, 6253.6560489173535, 5927.496162826035, 5953.0, 5991.790287340096, 6014.789482368096, 6023.939760602338, 5927.585069045125, 5945.762169513344, 5948.583599148018, 5954.017587098254, 5988.101784732325, 5657.717114144359, 5677.261955945701, 5761.683107428964, 5777.8555536111935, 5783.132530120515, 5831.912521312269, 5893.528517718658, 5921.437463440966, 5948.702564871825, 6001.639901605918, 5850.063989761617, 5876.911846322223, 5886.411358864079, 5899.203607512931, 5962.970185149046], "value": 5599.73392871728, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [523.879507713226, 526.9552088072513, 534.7486681259809, 549.4313385645858, 575.5568212476393, 30408.69591304089, 41394.37908431376, 41654.583454165484, 42061.789691051505, 44897.775511122396, 43669.126617467635, 43869.7806510967, 43902.560974390246, 43905.34141987873, 43989.34015989755, 8728.694495692653, 9623.133917947365, 9820.950895245549, 9821.558029888636, 9824.705258842247, 8732.607032683545, 8914.465132092062, 8923.152300531472, 8925.196732294093, 11727.648170554898, 11701.122415818805, 11984.460699268553, 12021.639350819445, 12029.157958942878, 12064.879351206508, 11860.822087668692, 11906.28562286257, 11924.10569207313, 11925.701857453632, 11944.343061131634, 8708.21626053652, 11474.311541307548, 11646.941765291203, 11781.882181178249, 11852.11109166816, 41287.793561032006, 41459.37810932838, 41486.585134148874, 41519.1696166078, 41758.0, 2889.5376739721755, 3594.0475773919934, 3916.0014196380025, 4123.711340206187, 4147.274227010261, 31304.0, 32567.511487327472, 32893.50659740106, 32914.51884665172, 33008.17479563006, 3254.576905002358, 3325.401427743005, 3366.2425954160362, 3411.778234414755, 3455.550778398792, 477034.0, 480022.0, 482354.17645823443, 483278.0, 485602.0, 60424.093638595456, 60656.0, 60716.69641651763, 60833.69583152144, 60919.0, 52820.0, 64175.679121604095, 64268.67865660642, 64337.67831160814, 64468.67765661235, 16553.751693724604, 16812.907161034494, 16836.32654693823, 16843.494695159166, 16885.0, 13021.739565208643, 13027.869721302666, 13069.477220911058, 13077.738445231042, 13093.083484156208, 15109.244537773067, 15583.376664933276, 16916.91541542309, 17527.73708394375, 17550.912245438947, 7257.782266532013, 7341.586162434493, 7364.3372096511575, 7413.221611730798, 7487.737929172453, 909.4043401571981, 929.6002718830968, 942.006183476439, 1013.2400699475394, 1093.6992327110108, 30127.849360753054, 30196.0, 31142.0, 31398.215044623612, 31455.52816707751, 1024.5850430575629, 1101.1851230089835, 1135.8750537440874, 1152.9942350288195, 1160.2864238493266, 15760.92119539418, 15778.92110539463, 15874.841251587337, 16114.516564503083, 16210.35158593643, 545455.2727236418, 550277.0, 550885.0, 552346.0, 553648.0, 59902.70048649729, 59917.40082599205, 59936.700316498136, 60244.397556024756, 60245.69877150674, 1350.5543170753629, 1389.215093472185, 1415.1509094543603, 1415.4338264694, 1416.1007760072223, 30135.547966780956, 30349.089527314216, 30352.84823575868, 30854.691453085183, 31763.0, 50978.23532647087, 51234.74382628063, 51375.743121284155, 51391.74304128456, 51417.0, 465012.0, 466572.0, 466613.0, 466669.0, 466874.0, 35353.469697955064, 41930.580694192664, 42340.36489452722, 42710.57289427066, 42812.357814633426, 48058.27912581244, 48320.5167948316, 48581.27128093011, 49296.50703492919, 49450.0, 33404.66595334113, 38847.0, 40633.577824777174, 41068.0, 41155.0, 30731.385372292876, 31016.0695179145, 39913.0, 40128.79613611596, 41106.79446602748, 38727.22545549017, 38868.833934981994, 38889.02777430587, 39123.0, 39159.02102447462, 33996.3200735979, 34444.82777586096, 35576.46635300524, 35582.822085889406, 35746.64253357433, 25803.612945806202, 25859.482810344063, 25953.740462595888, 26021.73978260269, 26324.21027369182], "value": 20622.405967094306, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.8137227955323, 359.54337990751765, 387.71309231168937, 390.20008981587756, 408.97750623715683, 15739.449119280827, 15803.367865285389, 20537.589248215027, 20581.27965521207, 20789.16843326269, 17489.300427982897, 19308.71036934447, 20137.798622013775, 20952.68570971433, 20987.055582498786, 5005.524475174852, 5349.7592608332625, 5517.200005999128, 5518.061929471999, 6078.908816367758, 5979.820605381846, 6013.098035294719, 6036.667983260919, 6037.788677396292, 6050.576459647827, 5830.766769329243, 5916.615419997708, 5923.940760592403, 5966.343702192756, 5968.582199246054, 5860.970695146519, 5903.586748927577, 6000.429959153874, 6018.0, 6024.638521688713, 4993.101241776479, 4996.625253105995, 5000.124978128849, 5003.699778013331, 5042.546170844642, 13193.670158246025, 13710.588682339545, 13711.657208569766, 23219.0, 24469.63295550568, 2495.6131799571094, 2512.924612261617, 2599.272203782925, 2624.128648976772, 2738.561830107173, 10098.394096354243, 10162.237832162617, 10312.79374412515, 10455.947720261425, 15850.841491585166, 1809.1496996411797, 1837.6875931091747, 1889.8677092603523, 1890.8487321014422, 1943.7570303712037, 109829.45085274523, 111619.0, 122016.16975745371, 124039.75960240372, 124813.0, 11189.762047590351, 11438.771224575628, 11570.537178512765, 11592.594259201056, 11800.52797888092, 11757.882421175678, 14517.491887783877, 14520.201388923711, 14545.909056820781, 14590.416383344547, 8368.748937531884, 8553.187447192508, 8555.743327700178, 8661.523616201044, 8690.652373904974, 6905.620190889549, 6959.338862808025, 8562.143785621513, 8641.438306510057, 8646.178613031752, 4522.412086428743, 4585.10590434862, 4588.403507544064, 4619.4687610925175, 4622.422197225347, 1683.6951362693765, 1754.9561260968578, 1866.132248504452, 1869.6167285706397, 1924.7305377247196, 810.1898101898101, 925.8148370325962, 949.4208532794989, 1011.9121943910326, 1047.3611097230682, 20676.379708608765, 21247.256346027814, 21364.252251171136, 21394.251201207884, 21459.390545709135, 837.6481877611418, 842.3219308456629, 920.6179435534178, 933.295362001698, 935.8175155844681, 10934.617288394868, 11120.777584448266, 11877.524899003944, 11955.820662690066, 12273.754524909451, 488139.5593022012, 600609.0, 600750.0, 612584.0, 618003.0, 142620.0, 144142.0, 145423.0, 147015.0, 148186.0, 956.416585882611, 966.6375109334, 990.3166814897697, 990.9405435673739, 1008.9960489313033, 19308.4207473776, 19659.115339809458, 19715.80284197197, 19805.0, 19833.702494462304, 42998.0, 43989.0, 55769.1634625489, 58362.708186458796, 58893.70553147378, 79140.0, 84350.0, 85600.1439985617, 85980.0, 88844.5557772207, 8208.712695055645, 8224.83550329002, 8302.792430189294, 8530.976282846099, 8541.53021583819, 12368.623301959484, 13237.470501179845, 13320.733585328046, 13328.866711332763, 13371.866281337063, 11155.609553665583, 11219.71950701239, 11276.7744645105, 11385.886141138482, 11593.82609260845, 9249.676261330822, 9467.905320946702, 11123.944380278046, 11465.885341146815, 11499.712507187387, 10190.592376304865, 11597.18819682614, 11682.0, 11744.00175985051, 11888.28670279786, 8278.71024514139, 8328.875066874123, 8338.37462190326, 8365.163483651586, 8412.327013838756, 10786.784264314827, 11251.324920504796, 11328.94335528317, 11402.94298528502, 11417.486213120595], "value": 9544.564909774275, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1480.222882986432, 1485.5840364697885, 1701.9319227230912, 1846.593749375137, 1890.7447494588228, 8486.745397638073, 8765.430247033953, 8957.73126806196, 8965.551722413878, 8972.91027089729, 7817.726379576716, 8057.19428057194, 8527.104654011333, 8761.95619021904, 8773.034966153718, 2357.3045951444324, 2385.427497400629, 2389.307100940725, 2392.4018995251186, 2392.5932591459405, 2098.27609474731, 2351.1535847095033, 2354.411397150712, 2389.880505974703, 2390.6653068570413, 2147.6134295826746, 2282.8972696228625, 2370.277065495023, 2376.6791483149814, 2378.4529558201625, 2230.4200907763934, 2232.520008198229, 2236.0943817753837, 2337.2988103568946, 2362.173239366225, 2332.288651961151, 2339.3332900123382, 2357.6463530470396, 2362.267697013931, 2370.1230544698574, 8717.389782715212, 8779.956100219459, 8780.121987801233, 8798.384113112084, 8812.647494100229, 5202.193659982709, 5402.864928376783, 5413.458654134594, 5414.810481633163, 5423.728813559306, 3503.6321186275327, 3592.3354179476883, 3650.123970247148, 3677.9448308275396, 3683.1344634010984, 1001.6744558018673, 1018.9592416303338, 1025.933314334566, 1039.6517166749147, 1045.0907710292095, 11346.092312614972, 11554.711132221677, 11563.942180289128, 11576.884231157665, 11596.826047609293, 4552.7723613819835, 4575.519570445122, 4794.2089555223, 4799.496052914394, 4807.519248075235, 10009.899901001043, 10017.398956062514, 10027.899721002843, 10030.799384012425, 10044.045815647502, 5260.500252476085, 5331.760070796823, 5335.706536140549, 5336.679799211982, 5337.412884582693, 4124.195781822576, 4731.4795372509, 4884.3650325458175, 4892.437369702529, 4900.411950565884, 3835.328817456934, 3847.7306588539095, 3877.9806100969313, 3923.9803800981385, 3934.213157368538, 1042.671558459083, 1190.7678002789373, 1236.202649291202, 1274.1081243129847, 1275.2730943362194, 898.8292224477328, 903.8915330160423, 915.665781989569, 931.4039015030386, 939.572494514994, 5388.865278368072, 5724.856878578069, 5851.008045385724, 5864.941350586525, 5930.7924222652, 923.3951761596031, 945.5555888732284, 959.5058544849409, 974.8586454964133, 980.548947484151, 3475.391806433864, 3900.258950799339, 3956.940645890314, 3972.5431575368625, 3991.980040099839, 10178.643747468952, 10868.45657717111, 10883.83674244887, 10884.346939183675, 10888.020078193, 10621.628243011457, 10670.946645266724, 10829.458527073615, 10849.728756780985, 10898.182636302308, 8313.6674533018, 8370.079291278076, 8379.287760540301, 8399.286060684788, 8420.242178203991, 5749.626274292157, 5798.536117110537, 5835.358110607915, 5872.647641141545, 5908.113782932594, 9784.706458806248, 10066.245031622804, 10084.193264538966, 10221.795564088825, 10230.59077636916, 9493.525323733924, 10438.425886576311, 10472.68581942543, 10487.108595769445, 10498.47507624631, 7580.431467639839, 7585.431092667962, 7669.616519173907, 7673.117591476828, 7676.193999630182, 8213.342932565261, 8370.497770133812, 8406.789830254047, 8415.663373464993, 8424.410291279552, 7560.4329675273475, 7570.5836179010685, 7580.014598102432, 7668.57822819728, 7734.729284475016, 7433.8141546461775, 7449.515781474187, 7462.179160292472, 7583.544987300779, 7593.392528597588, 6772.86454270902, 7070.575765454089, 7078.221395646372, 7138.428925686037, 7143.571385716874, 4918.4097908249805, 4996.325496057985, 5018.372703412074, 5038.773255203451, 5140.357455318085, 5012.523810238022, 5105.897882042413, 5107.107815060245, 5133.435322114639, 5258.553022993088], "value": 4599.574554789031, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [277.7293880496728, 280.12042187531154, 295.5315824418297, 304.05135975755644, 306.79138186033504, 4283.764392958394, 4659.207934651105, 5148.768305426255, 5169.638125331228, 5171.870703232421, 4947.812524994002, 5159.974200129003, 5163.845084647466, 5165.457626949173, 5174.611904107198, 3988.0, 4002.2395744808537, 4005.9184020314497, 4007.0783719744477, 4023.41660459234, 3740.0836794985266, 3826.9808650956847, 3993.680505559563, 4020.3315624016, 4023.2355852387955, 3200.0, 3475.7393195510313, 3850.306944749944, 4010.9799451002705, 4026.530316434495, 2905.840178790176, 3495.4057810172203, 3927.5483319418345, 3983.223271462066, 3983.9084480647603, 3472.218750781054, 3472.826358682056, 3474.6872781449792, 3477.4957631143598, 3478.843452044664, 4390.8902277443, 4809.158397280462, 4840.975795121002, 5072.264521644341, 5074.644774865761, 4529.592336689714, 4530.4143549757655, 4547.931781023287, 4567.543245675439, 4580.564846339593, 5440.945590544084, 5446.459801129258, 5470.6444081135, 5477.493689235453, 5500.532454741352, 3699.981500092509, 3830.6743926766258, 3843.0968722350226, 3862.6909847212437, 3871.903202419962, 5209.062368773619, 5690.630109042899, 5712.971435142797, 5732.713364331767, 5745.626534275259, 3903.4535165076913, 3906.1797022625, 3909.941350879682, 3911.5501717302873, 3913.412988051815, 3292.3909076820864, 3833.15670552478, 3966.7619942803526, 3967.8294903003684, 3974.8608798692485, 3741.7006639469323, 4481.395011673382, 4520.841770538015, 4537.956270057905, 4549.886252843706, 5260.815871444481, 5505.587080968943, 5508.834734957958, 5526.198701188305, 5539.064295923167, 1246.956356527517, 1255.0837888341562, 1276.2278821313157, 1277.3740866975115, 1349.2107117336468, 783.0, 826.7147833997261, 897.9955100224457, 927.846905260638, 938.957746901391, 4412.757298348557, 4671.11248862715, 5113.53978141969, 5135.717535535507, 5156.690598564098, 5043.0, 5289.523942845162, 5618.494335509824, 5658.91511627326, 5664.037113690682, 4797.664163508521, 4971.701697898137, 5031.245313203049, 5063.974680126576, 5145.0, 2649.6555447791666, 2748.0, 2876.8561571921737, 2934.1784300395925, 2938.266422810509, 4503.636554233851, 5029.396472423259, 5662.292213473316, 5775.768969241183, 5793.8551536211935, 4294.828206871753, 5541.778328866801, 5647.463490968352, 5789.899919015374, 5791.148885990769, 5222.668219604053, 5345.9465405347, 5525.226468294442, 5776.33572139201, 5792.739326730307, 4858.90282194347, 5279.26090347344, 5604.971975140262, 5606.691631960118, 5642.605017648725, 5431.071969450788, 5514.61397702157, 5544.889102218014, 5546.694931778791, 5547.084731019337, 5026.271190677405, 5458.563314934875, 5774.39368866263, 5793.478586927196, 5795.652260864362, 2942.7346241116525, 3837.9424308635953, 3898.6686131679126, 3905.2970465316516, 3915.138669492707, 4858.684185527929, 5425.697832520245, 5512.448755124456, 5571.721413929206, 5586.804461843816, 5111.948880511147, 5424.050791111461, 5426.612290592127, 5431.2667789847865, 5453.700046497322, 4713.175194341045, 4959.876003099951, 5394.300795249484, 5417.968261901787, 5437.4018857926385, 4679.578837904605, 4719.716816990992, 4918.69654541554, 4924.76880779805, 5295.867603309948, 4211.810468528863, 5230.0, 5454.699991500347, 5494.203340515683, 5494.313210848644, 4415.933760993524, 5387.649802762808, 5400.702961337164, 5423.213634023124, 5439.156930675752], "value": 3985.6942191565004, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [663.7610460234315, 762.1844626249912, 787.4684587903166, 791.8099656082542, 831.6507067031846, 15047.172405517693, 16122.838771612265, 16139.919300403511, 16174.2721577529, 16364.936279141877, 14453.0, 14622.780658290107, 14633.634159146026, 15341.76987345188, 16116.274767635456, 817.9631916563769, 843.6203708331245, 845.9196376344238, 846.2679781988597, 848.0120659431755, 821.0885916632533, 834.1616675241374, 834.6285902773268, 835.0230230630177, 836.2891542189151, 890.4790697442004, 892.4199270474179, 892.723255790707, 893.3746377535751, 894.6197865906996, 883.2933653077577, 883.567052144451, 885.6191837509868, 885.8848349714528, 888.0364804187465, 650.4698670583507, 651.9087327774115, 652.5921299188008, 653.0367707631253, 657.2047822135222, 14769.70460590793, 15214.391424343125, 15258.00822946505, 15271.618209544738, 15951.122688252142, 13774.242416667079, 14010.719785604333, 14116.294185290797, 14163.716725665428, 14366.497172599013, 11802.11484138693, 12102.455389507493, 12468.501259949591, 12482.81275780864, 12508.0, 3705.555333360014, 3776.848926042955, 3854.2869569129803, 3896.0649444133487, 3949.3483575210057, 110562.0, 110638.44680776625, 113628.0, 118002.0, 118427.0, 10939.507722152523, 10947.397893115796, 11040.392778397265, 11080.556777728962, 11538.0, 21648.675269870964, 21930.45173870666, 21938.561228775336, 21955.560888782136, 21955.780442195693, 7766.922330776733, 7883.881741773878, 7932.0, 7957.283844454027, 8191.0580283267, 4370.590939067532, 4385.934210986838, 4452.955470445254, 4613.746243956614, 4622.791974361095, 3467.2718729066673, 3598.3882739934274, 3603.549556305462, 3603.819809009539, 3609.8556057757396, 607.1131768441122, 616.4975544930867, 625.0499241153422, 625.1060982794556, 625.8466675664491, 519.2548692626094, 551.9613627046068, 577.8439821248239, 589.381149792716, 662.3740565165969, 8711.738647840575, 9333.43999360027, 9447.149756521945, 9489.193418559362, 9597.136257736698, 552.7706002009202, 590.10598942602, 621.4624349937362, 624.3849807939176, 706.9257727938596, 4591.0, 4601.44782626087, 4656.976715116402, 4679.859604211879, 4756.833510827105, 142077.0, 155957.0, 156234.0, 156539.0, 163009.18495407683, 79853.0, 117234.41382793032, 129811.0, 132905.0, 134819.0, 115827.84172158339, 119123.0, 119274.0, 120279.0, 123575.0, 9128.589213485542, 9813.950930245303, 10123.848142277724, 10155.746106347106, 10195.694129176136, 46400.0, 57611.71194144002, 59850.0, 60069.69965150146, 60263.0, 88114.0, 89291.21417571742, 90889.0911090907, 91701.0, 91934.54032730061, 11706.35615041181, 11998.0, 13119.27843968591, 13177.27524986135, 13389.25939627881, 14834.10995340283, 14948.25258737037, 15080.0, 15136.167510787012, 15874.126923019345, 13837.584872453843, 13960.790588140982, 14059.22674252885, 14516.854831451972, 14538.563843084725, 12220.144589878979, 13544.59366219015, 13611.931940340235, 14142.43430262778, 14202.0, 13216.207027578375, 13559.932200338935, 13603.183808971493, 13707.657308567366, 14000.859991400363, 11179.944100279447, 11501.942490287494, 11588.826167607323, 11687.298762074302, 12020.0, 11040.447977600927, 11399.20205585601, 11441.48513316886, 11582.305061696325, 11700.531978720755], "value": 7862.6827786528165, "unit": "score"}], "name": "dromaeo_css", "value": 7359.539688078157, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 113.0, 112.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 114.0, 106.0, 156.0, 107.0, 107.0, 124.0, 110.0, 140.0, 107.0], "value": 121.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 183.0, 184.0, 183.0, 184.0, 181.0, 182.0, 183.0, 183.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 73.0, 58.0, 106.0, 59.0, 57.0, 63.0, 68.0, 82.0, 57.0], "value": 70.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 95.0, 89.0, 71.0, 71.0, 70.0, 94.0, 70.0, 68.0, 100.0], "value": 81.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 85.0, 88.0, 84.0, 88.0, 86.0, 88.0, 85.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 95.0, 93.0, 93.0], "value": 93.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 92.0, 84.0, 84.0, 84.0, 84.0, 85.0, 86.0, 85.0], "value": 85.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 52.0, 54.0, 54.0, 53.0, 55.0, 54.0, 54.0, 53.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 74.0, 77.0, 75.0, 75.0, 71.0, 69.0, 70.0, 71.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 84.0, 85.0, 83.0, 81.0, 86.0, 81.0, 82.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 138.0, 139.0, 145.0, 137.0, 148.0, 151.0, 247.0, 138.0], "value": 154.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 172.0, 172.0, 172.0, 172.0, 173.0, 171.0, 173.0, 170.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 68.0, 67.0, 68.0, 71.0, 73.0, 88.0, 86.0], "value": 72.4, "unit": "score"}], "name": "kraken", "value": 1441.3000000000002, "alertThreshold": 2.0}]} 06:07:26 INFO - Return code: 0 06:07:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:07:27 INFO - # TBPL SUCCESS # 06:07:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:07:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:07:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:07:27 INFO - 2016-06-30 06:07:27.044 screenresolution[1669:11047] starting screenresolution argv=screenresolution get 06:07:27 INFO - 2016-06-30 06:07:27.047 screenresolution[1669:11047] Display 0: 1600x1200x32@60 06:07:27 INFO - 2016-06-30 06:07:27.052 screenresolution[1670:11050] starting screenresolution argv=screenresolution list 06:07:27 INFO - Available Modes on Display 0 06:07:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:07:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:07:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:07:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:07:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:07:27 INFO - Intel Iris: 06:07:27 INFO - Chipset Model: Intel Iris 06:07:27 INFO - Type: GPU 06:07:27 INFO - Bus: Built-In 06:07:27 INFO - VRAM (Dynamic, Max): 1536 MB 06:07:27 INFO - Vendor: Intel (0x8086) 06:07:27 INFO - Device ID: 0x0a2e 06:07:27 INFO - Revision ID: 0x0009 06:07:27 INFO - Displays: 06:07:27 INFO - Display: 06:07:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:07:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:07:27 INFO - Display Serial Number: 1600x1200 60 06:07:27 INFO - Main Display: Yes 06:07:27 INFO - Mirror: Off 06:07:27 INFO - Online: Yes 06:07:27 INFO - Rotation: Supported 06:07:27 INFO - Return code: 0 06:07:27 INFO - Running post-action listener: _resource_record_post_action 06:07:27 INFO - ##### 06:07:27 INFO - ##### Finished run-tests step (success) 06:07:27 INFO - ##### 06:07:27 INFO - Running post-run listener: _resource_record_post_run 06:07:27 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 31723008; Write bytes: 706829312; Read time: 196; Write time: 1934 06:07:27 INFO - TinderboxPrint: CPU usage
53.4% 06:07:27 INFO - TinderboxPrint: I/O read bytes / time
31,723,008 / 196 06:07:27 INFO - TinderboxPrint: I/O write bytes / time
706,829,312 / 1,934 06:07:27 INFO - TinderboxPrint: CPU idle
2,255.8 (46.6%) 06:07:27 INFO - TinderboxPrint: CPU system
962.9 (19.9%) 06:07:27 INFO - TinderboxPrint: CPU user
1,619.0 (33.5%) 06:07:27 INFO - TinderboxPrint: Swap in / out
435,118,080 / 0 06:07:27 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 385772032; Write bytes: 380172288; Read time: 28964; Write time: 767 06:07:27 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 30809600; Write bytes: 297161728; Read time: 185; Write time: 1098 06:07:27 INFO - Running post-run listener: _upload_blobber_files 06:07:27 INFO - Blob upload gear active. 06:07:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:07:27 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 06:07:27 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'] 06:07:27 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 06:07:28 INFO - (blobuploader) - INFO - Open directory for files ... 06:07:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:07:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:07:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:07:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:07:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:07:28 INFO - (blobuploader) - INFO - Done attempting. 06:07:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:07:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:07:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:07:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:07:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:07:29 INFO - (blobuploader) - INFO - Done attempting. 06:07:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:07:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:07:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:07:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:07:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:07:33 INFO - (blobuploader) - INFO - Done attempting. 06:07:33 INFO - (blobuploader) - INFO - Iteration through files over. 06:07:33 INFO - Return code: 0 06:07:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:07:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:07:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/60b525f09214d6dd7e7a39527ffb800fe046543cb8da1c3cdf84f7c0b910aba686cb11fe8ed3ef4d618473140fb7e380ef9618c80b09e143aa7df62cd9654909", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ade2b323ae78af1ea322f0b8b3d21e2a921babd6390a6b9927dd145ee8eb4159c69becf444df5dc4c31be192f3400b6947e130db3982e8cc8154e8e9c4b351bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/83b35907d2ae793f5309a5cc0c5b14907188f74e07734637b74ea8fd6f4ad16d141c4fb5309d971d32a4314c0390f82937f3ee84c517212d6f00446509b58d7d"} 06:07:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:07:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:07:33 INFO - Contents: 06:07:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/60b525f09214d6dd7e7a39527ffb800fe046543cb8da1c3cdf84f7c0b910aba686cb11fe8ed3ef4d618473140fb7e380ef9618c80b09e143aa7df62cd9654909", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ade2b323ae78af1ea322f0b8b3d21e2a921babd6390a6b9927dd145ee8eb4159c69becf444df5dc4c31be192f3400b6947e130db3982e8cc8154e8e9c4b351bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/83b35907d2ae793f5309a5cc0c5b14907188f74e07734637b74ea8fd6f4ad16d141c4fb5309d971d32a4314c0390f82937f3ee84c517212d6f00446509b58d7d"} 06:07:33 INFO - Running post-run listener: copy_logs_to_upload_dir 06:07:33 INFO - Copying logs to upload dir... 06:07:33 INFO - mkdir: /builds/slave/test/build/upload/logs 06:07:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.523395 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2016-06-30 06:07:33.262634) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-30 06:07:33.266644) ========= 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.ITFhZSXBLk/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.pgryuwUo6F/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/60b525f09214d6dd7e7a39527ffb800fe046543cb8da1c3cdf84f7c0b910aba686cb11fe8ed3ef4d618473140fb7e380ef9618c80b09e143aa7df62cd9654909", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ade2b323ae78af1ea322f0b8b3d21e2a921babd6390a6b9927dd145ee8eb4159c69becf444df5dc4c31be192f3400b6947e130db3982e8cc8154e8e9c4b351bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/83b35907d2ae793f5309a5cc0c5b14907188f74e07734637b74ea8fd6f4ad16d141c4fb5309d971d32a4314c0390f82937f3ee84c517212d6f00446509b58d7d"} build_url:https://queue.taskcluster.net/v1/task/Dyy_7w0eSXKvQm6HCnarNA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011200 build_url: 'https://queue.taskcluster.net/v1/task/Dyy_7w0eSXKvQm6HCnarNA/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/60b525f09214d6dd7e7a39527ffb800fe046543cb8da1c3cdf84f7c0b910aba686cb11fe8ed3ef4d618473140fb7e380ef9618c80b09e143aa7df62cd9654909", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ade2b323ae78af1ea322f0b8b3d21e2a921babd6390a6b9927dd145ee8eb4159c69becf444df5dc4c31be192f3400b6947e130db3982e8cc8154e8e9c4b351bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/83b35907d2ae793f5309a5cc0c5b14907188f74e07734637b74ea8fd6f4ad16d141c4fb5309d971d32a4314c0390f82937f3ee84c517212d6f00446509b58d7d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-30 06:07:33.299177) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 06:07:33.299542) ========= 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.ITFhZSXBLk/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.pgryuwUo6F/Listeners TMPDIR=/var/folders/r0/nyzdpbbd3x92v1nh78hx68lh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005303 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-30 06:07:33.348397) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-30 06:07:33.348744) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-30 06:07:33.349137) ========= ========= Total master_lag: 0.21 =========